Skip to content

Instantly share code, notes, and snippets.

@shapovalovei

shapovalovei/Session-WebDriverAgentRunner-2017-05-09_191403-1GT2sF.log

Created May 10, 2017 03:03
Show Gist options
  • Save shapovalovei/84ee8271fbc95bb2f80bdcd79a3540e5 to your computer and use it in GitHub Desktop.
Save shapovalovei/84ee8271fbc95bb2f80bdcd79a3540e5 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
Session-WebDriverAgentRunner-2017-05-09_191403-1GT2sF.log
19:14:03.294 xcodebuild[87769:30903968] Beginning test session WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3 at 2017-05-09 19:14:03.292 with Xcode 8E1000a on target <DVTiOSDevice: 0x7fd2cdee3230> {
deviceSerialNumber: F17NFAHCG5MD
identifier: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
deviceClass: iPhone
deviceName: iPhone
deviceIdentifier: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
productVersion: 10.0.1
buildVersion: 14A403
deviceSoftwareVersion: 10.0.1 (14A403)
deviceArchitecture: arm64
deviceTotalCapacity: 12131713024
deviceAvailableCapacity: 4868018176
deviceIsTransient: NO
ignored: NO
deviceIsBusy: NO
deviceIsActivated: YES
deviceActivationState: Activated
isPasscodeLocked: NO
deviceType: <DVTDeviceType:0x7fd2d062cd00 Xcode.DeviceType.iPhone>
supportedDeviceFamilies: (
1
)
applications: (null)
provisioningProfiles: (null)
activityProgress: -2
activityTitle:
hasInternalSupport: NO
isSupportedOS: YES
developerDiskMountError: (null)
(null)
bootArgs: <unavailable>
} (10.0.1 (14A403))
19:14:03.294 xcodebuild[87769:30903968] /Applications/Xcode.app/Contents/Developer/usr/bin/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=599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
-configuration
Debug
IPHONEOS_DEPLOYMENT_TARGET=10.0
-xcconfig
/var/folders/zt/knp5y0sd3f373g8xvg0mmzc00000gp/T/201749-81513-dz7fd5.byajdcxr/appium-temp.xcconfig
19:14:03.294 xcodebuild[87769:30903968] Launching with Xcode.IDEFoundation.Launcher.PosixSpawn
19:14:03.304 xcodebuild[87769:30903968] Launch session started.
19:14:03.322 xcodebuild[87769:30903997] Connecting to mobile device: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
19:14:03.328 xcodebuild[87769:30903997] Starting session with mobile device
19:14:03.406 xcodebuild[87769:30903997] Starting test manager service on mobile device.
19:14:03.416 xcodebuild[87769:30904074] Received connection for test runner session
19:14:03.417 xcodebuild[87769:30904074] Listening for proxy connection request from the test bundle
19:14:03.418 xcodebuild[87769:30903968] Test runner session acquired connection.
19:14:03.418 xcodebuild[87769:30903968] Waiting for test process to launch.
19:14:03.418 xcodebuild[87769:30904074] Initiating session with identifier: 4FF55857-5A92-49AB-86C5-2F93A67689D3
19:14:03.452 xcodebuild[87769:30904076] Got reply to session initiation request with identifier 4FF55857-5A92-49AB-86C5-2F93A67689D3 (result:error): 22: (null)
19:14:03.657 xcodebuild[87769:30904077] Installing com.apple.test.WebDriverAgentRunner-Runner using MobileInstall
19:14:03.777 xcodebuild[87769:30904076] app uninstall set - {(
)}
19:14:03.777 xcodebuild[87769:30904076] app install set - {(
<DVTFilePath:0x7fd2d05e9fe0:'/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/WebDriverAgentRunner-Runner.app'>
)}
19:14:05.586 xcodebuild[87769:30904077] remoteExecutable setInstalledPath:/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app
19:14:05.587 xcodebuild[87769:30904077] Configuring for testing on device...
19:14:05.587 xcodebuild[87769:30904077] Updating test configuration with device paths.
19:14:05.588 xcodebuild[87769:30904077] Updated test configuration at /var/folders/zt/knp5y0sd3f373g8xvg0mmzc00000gp/T/com.apple.dt.XCTest/4FF55857-5A92-49AB-86C5-2F93A67689D3/remote-container/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration with device paths: <XCTestConfiguration: 0x7fd2d0ca9690>
testBundleURL:file:///private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest
testBundleRelativePath:(null)
productModuleName:WebDriverAgentRunner
testsToSkip:(null)
testsToRun:(null)
reportResultsToIDE:YES
sessionIdentifier:4FF55857-5A92-49AB-86C5-2F93A67689D3
pathToXcodeReportingSocket:(null)
disablePerformanceMetrics:no
treatMissingBaselinesAsFailures:no
baselineFileURL:(null)
baselineFileRelativePath:(null)
targetApplicationPath:(null)
targetApplicationBundleID:(null)
targetApplicationArguments:(null)
targetApplicationEnvironment:
(null)
reportActivities:YES
testsMustRunOnMainThread:YES
initializeForUITesting:YES
aggregateStatisticsBeforeCrash:{
XCSuiteRecordsKey = {
};
}
automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework
emitOSLogs:no
19:14:05.588 xcodebuild[87769:30904077] Uploading test configuration to app container.
19:14:07.556 xcodebuild[87769:30904077] Updating test process environment variables.
19:14:07.556 xcodebuild[87769:30904077] Updated testing environment variables {
"DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration";
}
19:14:08.842 xcodebuild[87769:30903968] Adding console adaptor <IDEConsoleAdaptor: 0x7fd2d0f0da60 'target'> for test session 4FF55857-5A92-49AB-86C5-2F93A67689D3.
19:14:08.962 xcodebuild[87769:30903968] Test process runnable PID is 4751.
19:14:09.444 xcodebuild[87769:30904033] Handling proxy channel request from test runner
19:14:09.444 xcodebuild[87769:30904033] Test runner is ready, running protocol 22, requires at least version 8. Control framework is running 22 and requires at least 8
19:14:09.444 xcodebuild[87769:30903968] Test runner session successfully initiated.
19:14:09.444 xcodebuild[87769:30903968] Creating the daemon control session.
19:14:09.445 xcodebuild[87769:30903968] Whitelisting test process ID 4751
19:14:09.445 xcodebuild[87769:30903968] Got authorization request for pid 4751
19:14:09.529 xcodebuild[87769:30904074] Requesting authorization for pid 4751
19:14:03.451 testmanagerd[4748:1065273] Launched testmanagerd from /Developer/usr/libexec/testmanagerd, built at Sunday, July 31, 2016 at 6:31:58 PM Pacific Daylight Time
19:14:03.452 testmanagerd[4748:1065273] Setting availability state.
19:14:09.534 xcodebuild[87769:30904076] Got reply to authorization request for pid 4751 (result:error): 22: (null)
19:14:03.453 testmanagerd[4748:1065273] Posting availability to test bundles...
19:14:09.534 xcodebuild[87769:30903968] Got whitelisting response from test daemon. Success: 1
19:14:03.456 testmanagerd[4748:1065275] IDE connected via lockdown over socket 4 (__54-[XCTestManagerIDEServer initWithLockdownServiceName:]_block_invoke)
19:14:03.456 testmanagerd[4748:1065275] Created session with socket 4
19:14:03.461 testmanagerd[4748:1065276] Handled a proxy request from the IDE with session <XCIDESession: 0x10020e380> proxy <_DTXProxy: 0x100211c80>
19:14:03.462 testmanagerd[4748:1065273] __84-[XCIDESession _IDE_initiateSessionWithIdentifier:forClient:atPath:protocolVersion:]_block_invoke 4FF55857-5A92-49AB-86C5-2F93A67689D3 1 4 from client 8F75F5A1-8F61-4D3F-AEB2-03CE2EA49D23-87769-00081356BC1C9E5E (/Applications/Xcode.app/Contents/Developer/usr/bin/xcodebuild)
19:14:03.462 testmanagerd[4748:1065275] __45-[XCTestManager setIDESession:forIdentifier:]_block_invoke <XCIDESession: 0x10020e380> -> 4FF55857-5A92-49AB-86C5-2F93A67689D3
19:14:03.462 testmanagerd[4748:1065275] -[XCTestManager _handleRequestForSessionWithIdentifier:]
19:14:08.458 testmanagerd[4748:1065273] Considering exiting, 0 test sessions, 1 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.328 testmanagerd[4748:1065275] Received new test session connection (-[XCTestManager listener:shouldAcceptNewConnection:])
19:14:09.333 testmanagerd[4748:1065273] Initial orientation 1
19:14:09.337 testmanagerd[4748:1065503] app info {
BKSApplicationStateAppIsFrontmost = 1;
BKSApplicationStateExtensionKey = 0;
SBApplicationStateDisplayIDKey = "com.apple.test.WebDriverAgentRunner-Runner";
SBApplicationStateKey = 8;
SBApplicationStateProcessIDKey = 4751;
SBMostElevatedStateForProcessID = 8;
}
19:14:09.337 testmanagerd[4748:1065503] Got request for process assertion for pid 4751.
19:14:09.337 testmanagerd[4748:1065503] Created process assertion for pid 4751: <BKSProcessAssertion: 0x1003254f0>, waiting for response from assertiond.
19:14:09.338 testmanagerd[4748:1065275] Test session 4FF55857-5A92-49AB-86C5-2F93A67689D3 requested socket (-[XCTestSession _XCT_requestSocketForSessionIdentifier:reply:])
19:14:09.551 testmanagerd[4748:1065273] Received request to collect crash reports in directories '/var/mobile/Library/Logs/CrashReporter/' for processes with names 'XCTRunner', 'WebDriverAgentRunner-Runner', 'xctest'
19:14:09.339 testmanagerd[4748:1065504] Test session 4FF55857-5A92-49AB-86C5-2F93A67689D3 requested socket (__57-[XCTestManager requestSocketForSessionIdentifier:reply:]_block_invoke)
19:14:09.339 testmanagerd[4748:1065504] -[XCTestManager _handleRequestForSessionWithIdentifier:]
19:14:09.339 testmanagerd[4748:1065504] Closing IDE connection before sending socket 5 to test host.
19:14:09.339 testmanagerd[4748:1065504] -[XCIDESession closeIDEConnection:] sending barrier
19:14:09.338 testmanagerd[4748:1065503] Starting to monitor com.apple.test.WebDriverAgentRunner-Runner
19:14:09.552 testmanagerd[4748:1065273] Initializing crash report observer for /var/mobile/Library/Logs/CrashReporter/
19:14:09.340 testmanagerd[4748:1065275] Got reply for process assertion request, success: YES
19:14:09.340 testmanagerd[4748:1065273] barrier flushed, closing DTXConnection to IDE, socket will not be closed
19:14:09.552 testmanagerd[4748:1065273] Getting files in /var/mobile/Library/Logs/CrashReporter/
19:14:09.340 testmanagerd[4748:1065503] DTXSocketTransport disconnected
19:14:09.341 testmanagerd[4748:1065503] Disconnecting lockdown socket
19:14:09.341 testmanagerd[4748:1065284] __33-[XCTestManager closeIDESession:]_block_invoke <XCIDESession: 0x10020e380>
19:14:09.341 testmanagerd[4748:1065284] Considering exiting, 1 test sessions, 0 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.342 testmanagerd[4748:1065284] Got application state update: com.apple.test.WebDriverAgentRunner-Runner (4751) -> 8
19:14:09.448 testmanagerd[4748:1065273] Sending socket 5 to test host
19:14:09.449 testmanagerd[4748:1065284] __33-[XCTestManager closeIDESession:]_block_invoke <XCIDESession: 0x10020e380>
19:14:09.560 testmanagerd[4748:1065273] Found 10 files in /var/mobile/Library/Logs/CrashReporter/
19:14:09.450 testmanagerd[4748:1065284] Considering exiting, 1 test sessions, 0 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.561 testmanagerd[4748:1065273] Started monitoring /var/mobile/Library/Logs/CrashReporter/
19:14:09.536 testmanagerd[4748:1065275] IDE connected via lockdown over socket 6 (__54-[XCTestManagerIDEServer initWithLockdownServiceName:]_block_invoke)
19:14:09.537 testmanagerd[4748:1065275] Created session with socket 6
19:14:09.542 testmanagerd[4748:1065503] Handled a proxy request from the IDE with session <XCIDESession: 0x10021b080> proxy <_DTXProxy: 0x10032f540>
19:14:09.542 testmanagerd[4748:1065503] -[XCIDESession _IDE_initiateControlSessionForTestProcessID:protocolVersion:] 4751 22
19:14:09.543 testmanagerd[4748:1065273] Set control session <XCIDESession: 0x10021b080> with proto 22 and proxy <_DTXProxy: 0x10032f540>
19:14:09.320 XCTRunner[4751:1065460] Linked XCTest.framework from /var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest, built with Xcode 0831(), modified on Monday, May 8, 2017 at 10:01:43 AM Pacific Daylight Time
19:14:09.321 XCTRunner[4751:1065460] XCTest.framework bundle version: 12124
19:14:09.324 XCTRunner[4751:1065460] System uptime: 5d 19:35:30
19:14:09.324 XCTRunner[4751:1065460] Process arguments: (
"/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/XCTRunner",
"-NSTreatUnknownArgumentsAsOpen",
NO,
"-ApplePersistenceIgnoreState",
YES
)
19:14:09.324 XCTRunner[4751:1065460] Process environment: {
"CFFIXED_USER_HOME" = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F";
CLASSIC = 1;
"DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
HOME = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F";
LOGNAME = mobile;
NSUnbufferedIO = YES;
"OS_ACTIVITY_DT_MODE" = YES;
PATH = "/usr/bin:/bin:/usr/sbin:/sbin";
SHELL = "/bin/sh";
TMPDIR = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp";
USER = mobile;
"XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration";
"XPC_FLAGS" = 0x0;
"XPC_SERVICE_NAME" = "UIKitApplication:com.apple.test.WebDriverAgentRunner-Runner[0x1885]";
"__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";
}
19:14:09.328 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> entering wait loop for 5.00s
19:14:09.336 XCTRunner[4751:1065475] Got daemon protocol version 6
19:14:09.336 XCTRunner[4751:1065476] <XCTWaiter: 0x170017a20>: cancelWaiting
19:14:09.337 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> done waiting
19:14:09.337 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> cleaning up Exchange protocol versions with daemon
19:14:09.338 XCTRunner[4751:1065460] Requesting socket
19:14:09.338 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> entering wait loop for 30.00s
19:14:09.450 XCTRunner[4751:1065477] <XCTWaiter: 0x170017a80>: cancelWaiting
19:14:09.450 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> done waiting
19:14:09.450 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> cleaning up Socket for IDE session acquired
19:14:09.450 XCTRunner[4751:1065460] Creating transport with socket 4
19:14:09.451 XCTRunner[4751:1065460] Creating connection with transport <DTXSocketTransport: 0x17011d880>
19:14:09.453 XCTRunner[4751:1065460] Creating the proxy channel with connection <DTXConnection 0x1701f4000 : x1>
19:14:09.454 XCTRunner[4751:1065460] Messaging IDE proxy - _XCT_testBundleReadyWithProtocolVersion:22 minimumVersion:8
19:14:09.455 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> entering wait loop for 30.00s
19:14:09.457 XCTRunner[4751:1065478] _XCT_testBundleReadyWithProtocolVersion:minimumVersion: reply received
19:14:09.568 XCTRunner[4751:1065477] _IDE_startExecutingTestPlanWithProtocolVersion:22
19:14:09.568 XCTRunner[4751:1065478] <XCTWaiter: 0x170017b10>: cancelWaiting
19:14:09.568 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> done waiting
19:14:09.568 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> cleaning up IDE session ready for test plan
19:14:09.567 xcodebuild[87769:30903968] Starting test plan, clearing initialization timeout timer.
19:14:09.575 XCTRunner[4751:1065460] Initializing for UI testing.
19:14:09.576 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> entering wait loop for 20.00s
19:14:09.580 testmanagerd[4748:1065275] Performing block with diagnostics...
19:14:09.582 testmanagerd[4748:1065275] Waiting 15.0s for AX to finish loading...
19:14:09.584 testmanagerd[4748:1065275] _AXSApplicationAccessibilityEnabled() returned false
19:14:09.671 testmanagerd[4748:1065273] AX is loaded
19:14:09.734 testmanagerd[4748:1065275] AXDisableAccessibilityOnTermination() returned 0
19:14:09.735 testmanagerd[4748:1065275] Created system wide AX element <AXUIElement 0x100332c60> {pid=0}
19:14:09.736 testmanagerd[4748:1065275] Registered for accessibility notification 1021
19:14:09.737 testmanagerd[4748:1065275] Registered for accessibility notification 1000
19:14:09.737 testmanagerd[4748:1065275] Registered for accessibility notification 1005
19:14:09.738 testmanagerd[4748:1065275] Registered for accessibility notification 1001
19:14:09.739 testmanagerd[4748:1065275] Registered for accessibility notification 1011
19:14:09.739 testmanagerd[4748:1065275] Registered for accessibility notification 4002
19:14:09.740 testmanagerd[4748:1065275] Registered for accessibility notification 4000
19:14:09.741 testmanagerd[4748:1065275] Registered for accessibility notification 4001
19:14:09.742 testmanagerd[4748:1065275] Registered for accessibility notification 5000
19:14:09.743 testmanagerd[4748:1065275] Block succeeded, canceling diagnostics.
19:14:09.744 XCTRunner[4751:1065506] <XCTWaiter: 0x170017b90>: cancelWaiting
19:14:09.745 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> done waiting
19:14:09.746 testmanagerd[4748:1065274] AXUIElementSetMessagingTimeout(<AXUIElement 0x100332c60> {pid=0}, 15) -> 0
19:14:09.745 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> cleaning up Expect predicate `BLOCKPREDICATE(0x17424b6a0)` for object BLOCKPREDICATE(0x17424b6a0)
19:14:09.747 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> entering wait loop for 15.00s
19:14:09.753 XCTRunner[4751:1065478] <XCTWaiter: 0x170017ec0>: cancelWaiting
19:14:09.753 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> done waiting
19:14:09.753 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> cleaning up Expect predicate `BLOCKPREDICATE(0x170249f00)` for object BLOCKPREDICATE(0x170249f00)
19:14:09.754 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> entering wait loop for 15.00s
19:14:09.754 testmanagerd[4748:1065275] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.756 testmanagerd[4748:1065275] Registered for AX notification 4002
19:14:09.757 XCTRunner[4751:1065506] <XCTWaiter: 0x174016ae0>: cancelWaiting
19:14:09.757 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> done waiting
19:14:09.757 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> cleaning up Expect predicate `BLOCKPREDICATE(0x17424ea30)` for object BLOCKPREDICATE(0x17424ea30)
19:14:09.759 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> entering wait loop for 15.00s
19:14:09.759 testmanagerd[4748:1065275] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.761 testmanagerd[4748:1065275] Registered for AX notification 1006
19:14:09.764 XCTRunner[4751:1065480] <XCTWaiter: 0x170017f50>: cancelWaiting
19:14:09.764 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> done waiting
19:14:09.764 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> cleaning up Expect predicate `BLOCKPREDICATE(0x170246b40)` for object BLOCKPREDICATE(0x170246b40)
19:14:09.765 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> entering wait loop for 15.00s
19:14:09.765 testmanagerd[4748:1065503] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.767 testmanagerd[4748:1065503] Registered for AX notification 1021
19:14:09.768 XCTRunner[4751:1065480] <XCTWaiter: 0x174016b90>: cancelWaiting
19:14:09.768 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> done waiting
19:14:09.768 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e850)` for object BLOCKPREDICATE(0x17424e850)
19:14:09.769 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> entering wait loop for 30.00s
19:14:09.769 testmanagerd[4748:1065274] Got request for process assertion for pid 4751.
19:14:09.770 testmanagerd[4748:1065274] Invalidating previous process assertion for pid 4751.
19:14:09.771 testmanagerd[4748:1065274] Created process assertion for pid 4751: <BKSProcessAssertion: 0x100222fb0>, waiting for response from assertiond.
19:14:09.773 testmanagerd[4748:1065508] Got reply for process assertion request, success: YES
19:14:09.774 XCTRunner[4751:1065477] Got reply for background assertion for test process, acquired: YES.
19:14:09.774 XCTRunner[4751:1065506] <XCTWaiter: 0x170018000>: cancelWaiting
19:14:09.775 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> done waiting
19:14:09.775 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e3a0)` for object BLOCKPREDICATE(0x17424e3a0)
19:14:09.775 XCTRunner[4751:1065460] Registering for UIApplicationDidEnterBackgroundNotification
19:14:09.775 XCTRunner[4751:1065460] Hitting home button to enter background.
19:14:09.777 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> entering wait loop for 30.00s
19:14:09.778 testmanagerd[4748:1065284] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.778 testmanagerd[4748:1065284] Performing <XCDeviceEvent:0x100336320 page 12 usage 64 duration 0.01s
19:14:09.782 testmanagerd[4748:1065284] Dispatching device event synthesis to background queue
19:14:09.783 testmanagerd[4748:1065284] Sleeping for 0.005000s before sending up event
19:14:09.794 XCTRunner[4751:1065507] <XCTWaiter: 0x170018080>: cancelWaiting
19:14:09.794 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> done waiting
19:14:09.795 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e880)` for object BLOCKPREDICATE(0x17424e880)
19:14:09.795 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> entering wait loop for 30.00s
19:14:09.797 XCTRunner[4751:1065478] Got AX notification 4002
19:14:09.790 testmanagerd[4748:1065284] Device event sent.
19:14:09.792 testmanagerd[4748:1065273] AX Notification 4002
19:14:09.793 testmanagerd[4748:1065273] Got user testing notification with payload {
event = ButtonEventsCompleted;
}
19:14:09.793 testmanagerd[4748:1065273] Device event complete, replying to client.
19:14:10.035 XCTRunner[4751:1065478] Got AX notification 4002
19:14:09.999 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.038 XCTRunner[4751:1065478] Got AX notification 4002
19:14:10.039 XCTRunner[4751:1065478] Got AX notification 4002
19:14:10.001 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBLeafIconViewController;
event = ViewDidAppear;
}
19:14:10.005 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.006 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBLeafIconViewController;
event = ViewDidAppear;
}
19:14:10.007 testmanagerd[4748:1065273] AX Notification 1000
19:14:10.007 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.008 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBIconController;
event = ViewDidDisappear;
}
19:14:10.476 testmanagerd[4748:1065273] AX Notification 1011
19:14:14.629 XCTRunner[4751:1065475] <XCTWaiter: 0x170017a20> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:16.658 XCTRunner[4751:1065506] <XCTWaiter: 0x170017a20> watchdog second trigger, weakWaiter: (null)
19:14:25.079 XCTRunner[4751:1065507] <XCTWaiter: 0x170017ec0> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065506] <XCTWaiter: 0x174016ae0> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065478] <XCTWaiter: 0x170017f50> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065506] <XCTWaiter: 0x174016b90> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x170017ec0> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x174016ae0> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x170017f50> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x174016b90> watchdog second trigger, weakWaiter: (null)
19:14:29.857 XCTRunner[4751:1065582] <XCTWaiter: 0x170017b90> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:31.898 XCTRunner[4751:1065582] <XCTWaiter: 0x170017b90> watchdog second trigger, weakWaiter: (null)
19:14:39.611 XCTRunner[4751:1065581] <XCTWaiter: 0x170017a80> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:39.714 XCTRunner[4751:1065506] <XCTWaiter: 0x170017b10> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:39.826 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> done waiting
19:14:39.828 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> reporting timed out failure with Expect predicate `BLOCKPREDICATE(0x1702450a0)` for object BLOCKPREDICATE(0x1702450a0)
19:14:39.823 xcodebuild[87769:30903968] -[IDETestOperationCoordinator testRunnerSession:initializationForUITestingDidFailWithError:]
19:14:39.824 xcodebuild[87769:30903968] -[XCTTestRunnerSession _XCT_didFinishExecutingTestPlan]_block_invoke
19:14:39.824 xcodebuild[87769:30903968] Test operation failure: Failed to background test runner.
19:14:39.824 xcodebuild[87769:30903968] _finishWithError:Error Domain=IDETestOperationsObserverErrorDomain Code=13 "Failed to background test runner." UserInfo={NSLocalizedDescription=Failed to background test runner.} didCancel: 1
Raw
xcode logs.log
19:14:03.294 xcodebuild[87769:30903968] Beginning test session WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3 at 2017-05-09 19:14:03.292 with Xcode 8E1000a on target <DVTiOSDevice: 0x7fd2cdee3230> {
deviceSerialNumber: F17NFAHCG5MD
identifier: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
deviceClass: iPhone
deviceName: iPhone
deviceIdentifier: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
productVersion: 10.0.1
buildVersion: 14A403
deviceSoftwareVersion: 10.0.1 (14A403)
deviceArchitecture: arm64
deviceTotalCapacity: 12131713024
deviceAvailableCapacity: 4868018176
deviceIsTransient: NO
ignored: NO
deviceIsBusy: NO
deviceIsActivated: YES
deviceActivationState: Activated
isPasscodeLocked: NO
deviceType: <DVTDeviceType:0x7fd2d062cd00 Xcode.DeviceType.iPhone>
supportedDeviceFamilies: (
1
)
applications: (null)
provisioningProfiles: (null)
activityProgress: -2
activityTitle:
hasInternalSupport: NO
isSupportedOS: YES
developerDiskMountError: (null)
(null)
bootArgs: <unavailable>
} (10.0.1 (14A403))
19:14:03.294 xcodebuild[87769:30903968] /Applications/Xcode.app/Contents/Developer/usr/bin/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=599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
-configuration
Debug
IPHONEOS_DEPLOYMENT_TARGET=10.0
-xcconfig
/var/folders/zt/knp5y0sd3f373g8xvg0mmzc00000gp/T/201749-81513-dz7fd5.byajdcxr/appium-temp.xcconfig
19:14:03.294 xcodebuild[87769:30903968] Launching with Xcode.IDEFoundation.Launcher.PosixSpawn
19:14:03.304 xcodebuild[87769:30903968] Launch session started.
19:14:03.322 xcodebuild[87769:30903997] Connecting to mobile device: 599ee8713eddeb9f7f4c9a35ec69dbb8cf53f3c8
19:14:03.328 xcodebuild[87769:30903997] Starting session with mobile device
19:14:03.406 xcodebuild[87769:30903997] Starting test manager service on mobile device.
19:14:03.416 xcodebuild[87769:30904074] Received connection for test runner session
19:14:03.417 xcodebuild[87769:30904074] Listening for proxy connection request from the test bundle
19:14:03.418 xcodebuild[87769:30903968] Test runner session acquired connection.
19:14:03.418 xcodebuild[87769:30903968] Waiting for test process to launch.
19:14:03.418 xcodebuild[87769:30904074] Initiating session with identifier: 4FF55857-5A92-49AB-86C5-2F93A67689D3
19:14:03.452 xcodebuild[87769:30904076] Got reply to session initiation request with identifier 4FF55857-5A92-49AB-86C5-2F93A67689D3 (result:error): 22: (null)
19:14:03.657 xcodebuild[87769:30904077] Installing com.apple.test.WebDriverAgentRunner-Runner using MobileInstall
19:14:03.777 xcodebuild[87769:30904076] app uninstall set - {(
)}
19:14:03.777 xcodebuild[87769:30904076] app install set - {(
<DVTFilePath:0x7fd2d05e9fe0:'/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos/WebDriverAgentRunner-Runner.app'>
)}
19:14:05.586 xcodebuild[87769:30904077] remoteExecutable setInstalledPath:/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app
19:14:05.587 xcodebuild[87769:30904077] Configuring for testing on device...
19:14:05.587 xcodebuild[87769:30904077] Updating test configuration with device paths.
19:14:05.588 xcodebuild[87769:30904077] Updated test configuration at /var/folders/zt/knp5y0sd3f373g8xvg0mmzc00000gp/T/com.apple.dt.XCTest/4FF55857-5A92-49AB-86C5-2F93A67689D3/remote-container/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration with device paths: <XCTestConfiguration: 0x7fd2d0ca9690>
testBundleURL:file:///private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest
testBundleRelativePath:(null)
productModuleName:WebDriverAgentRunner
testsToSkip:(null)
testsToRun:(null)
reportResultsToIDE:YES
sessionIdentifier:4FF55857-5A92-49AB-86C5-2F93A67689D3
pathToXcodeReportingSocket:(null)
disablePerformanceMetrics:no
treatMissingBaselinesAsFailures:no
baselineFileURL:(null)
baselineFileRelativePath:(null)
targetApplicationPath:(null)
targetApplicationBundleID:(null)
targetApplicationArguments:(null)
targetApplicationEnvironment:
(null)
reportActivities:YES
testsMustRunOnMainThread:YES
initializeForUITesting:YES
aggregateStatisticsBeforeCrash:{
XCSuiteRecordsKey = {
};
}
automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework
emitOSLogs:no
19:14:05.588 xcodebuild[87769:30904077] Uploading test configuration to app container.
19:14:07.556 xcodebuild[87769:30904077] Updating test process environment variables.
19:14:07.556 xcodebuild[87769:30904077] Updated testing environment variables {
"DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration";
}
19:14:08.842 xcodebuild[87769:30903968] Adding console adaptor <IDEConsoleAdaptor: 0x7fd2d0f0da60 'target'> for test session 4FF55857-5A92-49AB-86C5-2F93A67689D3.
19:14:08.962 xcodebuild[87769:30903968] Test process runnable PID is 4751.
19:14:09.444 xcodebuild[87769:30904033] Handling proxy channel request from test runner
19:14:09.444 xcodebuild[87769:30904033] Test runner is ready, running protocol 22, requires at least version 8. Control framework is running 22 and requires at least 8
19:14:09.444 xcodebuild[87769:30903968] Test runner session successfully initiated.
19:14:09.444 xcodebuild[87769:30903968] Creating the daemon control session.
19:14:09.445 xcodebuild[87769:30903968] Whitelisting test process ID 4751
19:14:09.445 xcodebuild[87769:30903968] Got authorization request for pid 4751
19:14:09.529 xcodebuild[87769:30904074] Requesting authorization for pid 4751
19:14:03.451 testmanagerd[4748:1065273] Launched testmanagerd from /Developer/usr/libexec/testmanagerd, built at Sunday, July 31, 2016 at 6:31:58 PM Pacific Daylight Time
19:14:03.452 testmanagerd[4748:1065273] Setting availability state.
19:14:09.534 xcodebuild[87769:30904076] Got reply to authorization request for pid 4751 (result:error): 22: (null)
19:14:03.453 testmanagerd[4748:1065273] Posting availability to test bundles...
19:14:09.534 xcodebuild[87769:30903968] Got whitelisting response from test daemon. Success: 1
19:14:03.456 testmanagerd[4748:1065275] IDE connected via lockdown over socket 4 (__54-[XCTestManagerIDEServer initWithLockdownServiceName:]_block_invoke)
19:14:03.456 testmanagerd[4748:1065275] Created session with socket 4
19:14:03.461 testmanagerd[4748:1065276] Handled a proxy request from the IDE with session <XCIDESession: 0x10020e380> proxy <_DTXProxy: 0x100211c80>
19:14:03.462 testmanagerd[4748:1065273] __84-[XCIDESession _IDE_initiateSessionWithIdentifier:forClient:atPath:protocolVersion:]_block_invoke 4FF55857-5A92-49AB-86C5-2F93A67689D3 1 4 from client 8F75F5A1-8F61-4D3F-AEB2-03CE2EA49D23-87769-00081356BC1C9E5E (/Applications/Xcode.app/Contents/Developer/usr/bin/xcodebuild)
19:14:03.462 testmanagerd[4748:1065275] __45-[XCTestManager setIDESession:forIdentifier:]_block_invoke <XCIDESession: 0x10020e380> -> 4FF55857-5A92-49AB-86C5-2F93A67689D3
19:14:03.462 testmanagerd[4748:1065275] -[XCTestManager _handleRequestForSessionWithIdentifier:]
19:14:08.458 testmanagerd[4748:1065273] Considering exiting, 0 test sessions, 1 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.328 testmanagerd[4748:1065275] Received new test session connection (-[XCTestManager listener:shouldAcceptNewConnection:])
19:14:09.333 testmanagerd[4748:1065273] Initial orientation 1
19:14:09.337 testmanagerd[4748:1065503] app info {
BKSApplicationStateAppIsFrontmost = 1;
BKSApplicationStateExtensionKey = 0;
SBApplicationStateDisplayIDKey = "com.apple.test.WebDriverAgentRunner-Runner";
SBApplicationStateKey = 8;
SBApplicationStateProcessIDKey = 4751;
SBMostElevatedStateForProcessID = 8;
}
19:14:09.337 testmanagerd[4748:1065503] Got request for process assertion for pid 4751.
19:14:09.337 testmanagerd[4748:1065503] Created process assertion for pid 4751: <BKSProcessAssertion: 0x1003254f0>, waiting for response from assertiond.
19:14:09.338 testmanagerd[4748:1065275] Test session 4FF55857-5A92-49AB-86C5-2F93A67689D3 requested socket (-[XCTestSession _XCT_requestSocketForSessionIdentifier:reply:])
19:14:09.551 testmanagerd[4748:1065273] Received request to collect crash reports in directories '/var/mobile/Library/Logs/CrashReporter/' for processes with names 'XCTRunner', 'WebDriverAgentRunner-Runner', 'xctest'
19:14:09.339 testmanagerd[4748:1065504] Test session 4FF55857-5A92-49AB-86C5-2F93A67689D3 requested socket (__57-[XCTestManager requestSocketForSessionIdentifier:reply:]_block_invoke)
19:14:09.339 testmanagerd[4748:1065504] -[XCTestManager _handleRequestForSessionWithIdentifier:]
19:14:09.339 testmanagerd[4748:1065504] Closing IDE connection before sending socket 5 to test host.
19:14:09.339 testmanagerd[4748:1065504] -[XCIDESession closeIDEConnection:] sending barrier
19:14:09.338 testmanagerd[4748:1065503] Starting to monitor com.apple.test.WebDriverAgentRunner-Runner
19:14:09.552 testmanagerd[4748:1065273] Initializing crash report observer for /var/mobile/Library/Logs/CrashReporter/
19:14:09.340 testmanagerd[4748:1065275] Got reply for process assertion request, success: YES
19:14:09.340 testmanagerd[4748:1065273] barrier flushed, closing DTXConnection to IDE, socket will not be closed
19:14:09.552 testmanagerd[4748:1065273] Getting files in /var/mobile/Library/Logs/CrashReporter/
19:14:09.340 testmanagerd[4748:1065503] DTXSocketTransport disconnected
19:14:09.341 testmanagerd[4748:1065503] Disconnecting lockdown socket
19:14:09.341 testmanagerd[4748:1065284] __33-[XCTestManager closeIDESession:]_block_invoke <XCIDESession: 0x10020e380>
19:14:09.341 testmanagerd[4748:1065284] Considering exiting, 1 test sessions, 0 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.342 testmanagerd[4748:1065284] Got application state update: com.apple.test.WebDriverAgentRunner-Runner (4751) -> 8
19:14:09.448 testmanagerd[4748:1065273] Sending socket 5 to test host
19:14:09.449 testmanagerd[4748:1065284] __33-[XCTestManager closeIDESession:]_block_invoke <XCIDESession: 0x10020e380>
19:14:09.560 testmanagerd[4748:1065273] Found 10 files in /var/mobile/Library/Logs/CrashReporter/
19:14:09.450 testmanagerd[4748:1065284] Considering exiting, 1 test sessions, 0 sessions with identifiers, 0 unidentified sessions, does not have control session...
19:14:09.561 testmanagerd[4748:1065273] Started monitoring /var/mobile/Library/Logs/CrashReporter/
19:14:09.536 testmanagerd[4748:1065275] IDE connected via lockdown over socket 6 (__54-[XCTestManagerIDEServer initWithLockdownServiceName:]_block_invoke)
19:14:09.537 testmanagerd[4748:1065275] Created session with socket 6
19:14:09.542 testmanagerd[4748:1065503] Handled a proxy request from the IDE with session <XCIDESession: 0x10021b080> proxy <_DTXProxy: 0x10032f540>
19:14:09.542 testmanagerd[4748:1065503] -[XCIDESession _IDE_initiateControlSessionForTestProcessID:protocolVersion:] 4751 22
19:14:09.543 testmanagerd[4748:1065273] Set control session <XCIDESession: 0x10021b080> with proto 22 and proxy <_DTXProxy: 0x10032f540>
19:14:09.320 XCTRunner[4751:1065460] Linked XCTest.framework from /var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest, built with Xcode 0831(), modified on Monday, May 8, 2017 at 10:01:43 AM Pacific Daylight Time
19:14:09.321 XCTRunner[4751:1065460] XCTest.framework bundle version: 12124
19:14:09.324 XCTRunner[4751:1065460] System uptime: 5d 19:35:30
19:14:09.324 XCTRunner[4751:1065460] Process arguments: (
"/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/XCTRunner",
"-NSTreatUnknownArgumentsAsOpen",
NO,
"-ApplePersistenceIgnoreState",
YES
)
19:14:09.324 XCTRunner[4751:1065460] Process environment: {
"CFFIXED_USER_HOME" = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F";
CLASSIC = 1;
"DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
"DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/9DEF0B4E-7E25-4E6F-ADB8-DB30D50B3A2A/WebDriverAgentRunner-Runner.app/Frameworks:/Users/qaautomation/Library/Developer/Xcode/DerivedData/WebDriverAgent-brdadhpuduowllgivnnvuygpwhzy/Build/Products/Debug-iphoneos:__PLATFORMS__/iPhoneOS.platform/Developer/Library/Frameworks:/Developer/Library/PrivateFrameworks";
HOME = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F";
LOGNAME = mobile;
NSUnbufferedIO = YES;
"OS_ACTIVITY_DT_MODE" = YES;
PATH = "/usr/bin:/bin:/usr/sbin:/sbin";
SHELL = "/bin/sh";
TMPDIR = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp";
USER = mobile;
"XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";
XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/6715E248-E07D-4049-90C4-0452568B1A5F/tmp/WebDriverAgentRunner-4FF55857-5A92-49AB-86C5-2F93A67689D3.xctestconfiguration";
"XPC_FLAGS" = 0x0;
"XPC_SERVICE_NAME" = "UIKitApplication:com.apple.test.WebDriverAgentRunner-Runner[0x1885]";
"__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";
}
19:14:09.328 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> entering wait loop for 5.00s
19:14:09.336 XCTRunner[4751:1065475] Got daemon protocol version 6
19:14:09.336 XCTRunner[4751:1065476] <XCTWaiter: 0x170017a20>: cancelWaiting
19:14:09.337 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> done waiting
19:14:09.337 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a20> cleaning up Exchange protocol versions with daemon
19:14:09.338 XCTRunner[4751:1065460] Requesting socket
19:14:09.338 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> entering wait loop for 30.00s
19:14:09.450 XCTRunner[4751:1065477] <XCTWaiter: 0x170017a80>: cancelWaiting
19:14:09.450 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> done waiting
19:14:09.450 XCTRunner[4751:1065460] <XCTWaiter: 0x170017a80> cleaning up Socket for IDE session acquired
19:14:09.450 XCTRunner[4751:1065460] Creating transport with socket 4
19:14:09.451 XCTRunner[4751:1065460] Creating connection with transport <DTXSocketTransport: 0x17011d880>
19:14:09.453 XCTRunner[4751:1065460] Creating the proxy channel with connection <DTXConnection 0x1701f4000 : x1>
19:14:09.454 XCTRunner[4751:1065460] Messaging IDE proxy - _XCT_testBundleReadyWithProtocolVersion:22 minimumVersion:8
19:14:09.455 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> entering wait loop for 30.00s
19:14:09.457 XCTRunner[4751:1065478] _XCT_testBundleReadyWithProtocolVersion:minimumVersion: reply received
19:14:09.568 XCTRunner[4751:1065477] _IDE_startExecutingTestPlanWithProtocolVersion:22
19:14:09.568 XCTRunner[4751:1065478] <XCTWaiter: 0x170017b10>: cancelWaiting
19:14:09.568 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> done waiting
19:14:09.568 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b10> cleaning up IDE session ready for test plan
19:14:09.567 xcodebuild[87769:30903968] Starting test plan, clearing initialization timeout timer.
19:14:09.575 XCTRunner[4751:1065460] Initializing for UI testing.
19:14:09.576 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> entering wait loop for 20.00s
19:14:09.580 testmanagerd[4748:1065275] Performing block with diagnostics...
19:14:09.582 testmanagerd[4748:1065275] Waiting 15.0s for AX to finish loading...
19:14:09.584 testmanagerd[4748:1065275] _AXSApplicationAccessibilityEnabled() returned false
19:14:09.671 testmanagerd[4748:1065273] AX is loaded
19:14:09.734 testmanagerd[4748:1065275] AXDisableAccessibilityOnTermination() returned 0
19:14:09.735 testmanagerd[4748:1065275] Created system wide AX element <AXUIElement 0x100332c60> {pid=0}
19:14:09.736 testmanagerd[4748:1065275] Registered for accessibility notification 1021
19:14:09.737 testmanagerd[4748:1065275] Registered for accessibility notification 1000
19:14:09.737 testmanagerd[4748:1065275] Registered for accessibility notification 1005
19:14:09.738 testmanagerd[4748:1065275] Registered for accessibility notification 1001
19:14:09.739 testmanagerd[4748:1065275] Registered for accessibility notification 1011
19:14:09.739 testmanagerd[4748:1065275] Registered for accessibility notification 4002
19:14:09.740 testmanagerd[4748:1065275] Registered for accessibility notification 4000
19:14:09.741 testmanagerd[4748:1065275] Registered for accessibility notification 4001
19:14:09.742 testmanagerd[4748:1065275] Registered for accessibility notification 5000
19:14:09.743 testmanagerd[4748:1065275] Block succeeded, canceling diagnostics.
19:14:09.744 XCTRunner[4751:1065506] <XCTWaiter: 0x170017b90>: cancelWaiting
19:14:09.745 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> done waiting
19:14:09.746 testmanagerd[4748:1065274] AXUIElementSetMessagingTimeout(<AXUIElement 0x100332c60> {pid=0}, 15) -> 0
19:14:09.745 XCTRunner[4751:1065460] <XCTWaiter: 0x170017b90> cleaning up Expect predicate `BLOCKPREDICATE(0x17424b6a0)` for object BLOCKPREDICATE(0x17424b6a0)
19:14:09.747 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> entering wait loop for 15.00s
19:14:09.753 XCTRunner[4751:1065478] <XCTWaiter: 0x170017ec0>: cancelWaiting
19:14:09.753 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> done waiting
19:14:09.753 XCTRunner[4751:1065460] <XCTWaiter: 0x170017ec0> cleaning up Expect predicate `BLOCKPREDICATE(0x170249f00)` for object BLOCKPREDICATE(0x170249f00)
19:14:09.754 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> entering wait loop for 15.00s
19:14:09.754 testmanagerd[4748:1065275] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.756 testmanagerd[4748:1065275] Registered for AX notification 4002
19:14:09.757 XCTRunner[4751:1065506] <XCTWaiter: 0x174016ae0>: cancelWaiting
19:14:09.757 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> done waiting
19:14:09.757 XCTRunner[4751:1065460] <XCTWaiter: 0x174016ae0> cleaning up Expect predicate `BLOCKPREDICATE(0x17424ea30)` for object BLOCKPREDICATE(0x17424ea30)
19:14:09.759 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> entering wait loop for 15.00s
19:14:09.759 testmanagerd[4748:1065275] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.761 testmanagerd[4748:1065275] Registered for AX notification 1006
19:14:09.764 XCTRunner[4751:1065480] <XCTWaiter: 0x170017f50>: cancelWaiting
19:14:09.764 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> done waiting
19:14:09.764 XCTRunner[4751:1065460] <XCTWaiter: 0x170017f50> cleaning up Expect predicate `BLOCKPREDICATE(0x170246b40)` for object BLOCKPREDICATE(0x170246b40)
19:14:09.765 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> entering wait loop for 15.00s
19:14:09.765 testmanagerd[4748:1065503] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.767 testmanagerd[4748:1065503] Registered for AX notification 1021
19:14:09.768 XCTRunner[4751:1065480] <XCTWaiter: 0x174016b90>: cancelWaiting
19:14:09.768 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> done waiting
19:14:09.768 XCTRunner[4751:1065460] <XCTWaiter: 0x174016b90> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e850)` for object BLOCKPREDICATE(0x17424e850)
19:14:09.769 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> entering wait loop for 30.00s
19:14:09.769 testmanagerd[4748:1065274] Got request for process assertion for pid 4751.
19:14:09.770 testmanagerd[4748:1065274] Invalidating previous process assertion for pid 4751.
19:14:09.771 testmanagerd[4748:1065274] Created process assertion for pid 4751: <BKSProcessAssertion: 0x100222fb0>, waiting for response from assertiond.
19:14:09.773 testmanagerd[4748:1065508] Got reply for process assertion request, success: YES
19:14:09.774 XCTRunner[4751:1065477] Got reply for background assertion for test process, acquired: YES.
19:14:09.774 XCTRunner[4751:1065506] <XCTWaiter: 0x170018000>: cancelWaiting
19:14:09.775 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> done waiting
19:14:09.775 XCTRunner[4751:1065460] <XCTWaiter: 0x170018000> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e3a0)` for object BLOCKPREDICATE(0x17424e3a0)
19:14:09.775 XCTRunner[4751:1065460] Registering for UIApplicationDidEnterBackgroundNotification
19:14:09.775 XCTRunner[4751:1065460] Hitting home button to enter background.
19:14:09.777 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> entering wait loop for 30.00s
19:14:09.778 testmanagerd[4748:1065284] -[XCTestManager allowUITestControlForPID:] 4751 vs 4751
19:14:09.778 testmanagerd[4748:1065284] Performing <XCDeviceEvent:0x100336320 page 12 usage 64 duration 0.01s
19:14:09.782 testmanagerd[4748:1065284] Dispatching device event synthesis to background queue
19:14:09.783 testmanagerd[4748:1065284] Sleeping for 0.005000s before sending up event
19:14:09.794 XCTRunner[4751:1065507] <XCTWaiter: 0x170018080>: cancelWaiting
19:14:09.794 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> done waiting
19:14:09.795 XCTRunner[4751:1065460] <XCTWaiter: 0x170018080> cleaning up Expect predicate `BLOCKPREDICATE(0x17424e880)` for object BLOCKPREDICATE(0x17424e880)
19:14:09.795 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> entering wait loop for 30.00s
19:14:09.797 XCTRunner[4751:1065478] Got AX notification 4002
19:14:09.790 testmanagerd[4748:1065284] Device event sent.
19:14:09.792 testmanagerd[4748:1065273] AX Notification 4002
19:14:09.793 testmanagerd[4748:1065273] Got user testing notification with payload {
event = ButtonEventsCompleted;
}
19:14:09.793 testmanagerd[4748:1065273] Device event complete, replying to client.
19:14:10.035 XCTRunner[4751:1065478] Got AX notification 4002
19:14:09.999 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.038 XCTRunner[4751:1065478] Got AX notification 4002
19:14:10.039 XCTRunner[4751:1065478] Got AX notification 4002
19:14:10.001 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBLeafIconViewController;
event = ViewDidAppear;
}
19:14:10.005 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.006 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBLeafIconViewController;
event = ViewDidAppear;
}
19:14:10.007 testmanagerd[4748:1065273] AX Notification 1000
19:14:10.007 testmanagerd[4748:1065273] AX Notification 4002
19:14:10.008 testmanagerd[4748:1065273] Got user testing notification with payload {
controllerClass = SBIconController;
event = ViewDidDisappear;
}
19:14:10.476 testmanagerd[4748:1065273] AX Notification 1011
19:14:14.629 XCTRunner[4751:1065475] <XCTWaiter: 0x170017a20> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:16.658 XCTRunner[4751:1065506] <XCTWaiter: 0x170017a20> watchdog second trigger, weakWaiter: (null)
19:14:25.079 XCTRunner[4751:1065507] <XCTWaiter: 0x170017ec0> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065506] <XCTWaiter: 0x174016ae0> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065478] <XCTWaiter: 0x170017f50> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:25.079 XCTRunner[4751:1065506] <XCTWaiter: 0x174016b90> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x170017ec0> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x174016ae0> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x170017f50> watchdog second trigger, weakWaiter: (null)
19:14:27.122 XCTRunner[4751:1065478] <XCTWaiter: 0x174016b90> watchdog second trigger, weakWaiter: (null)
19:14:29.857 XCTRunner[4751:1065582] <XCTWaiter: 0x170017b90> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:31.898 XCTRunner[4751:1065582] <XCTWaiter: 0x170017b90> watchdog second trigger, weakWaiter: (null)
19:14:39.611 XCTRunner[4751:1065581] <XCTWaiter: 0x170017a80> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:39.714 XCTRunner[4751:1065506] <XCTWaiter: 0x170017b10> watchdog first trigger, clearing _XCWaitLoopIsValid and waking other waiters
19:14:39.826 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> done waiting
19:14:39.828 XCTRunner[4751:1065460] <XCTWaiter: 0x170018120> reporting timed out failure with Expect predicate `BLOCKPREDICATE(0x1702450a0)` for object BLOCKPREDICATE(0x1702450a0)
19:14:39.823 xcodebuild[87769:30903968] -[IDETestOperationCoordinator testRunnerSession:initializationForUITestingDidFailWithError:]
19:14:39.824 xcodebuild[87769:30903968] -[XCTTestRunnerSession _XCT_didFinishExecutingTestPlan]_block_invoke
19:14:39.824 xcodebuild[87769:30903968] Test operation failure: Failed to background test runner.
19:14:39.824 xcodebuild[87769:30903968] _finishWithError:Error Domain=IDETestOperationsObserverErrorDomain Code=13 "Failed to background test runner." UserInfo={NSLocalizedDescription=Failed to background test runner.} didCancel: 1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment