Xcode 5 instruments command-line tool prints internal logs running UIAutomation tests

Originator:jeff
Number:rdar://14194952 Date Originated:06.18.2013
Status:Open Resolved:
Product:Developer Tools Product Version:5.1 (5B130a), instruments 5.1 (55044)
Classification:UI/Usability Bug Reproducible:Always
 
18-Jun-2013 02:56 PM Jeffrey Wear:
Summary:

Depending on the SDK used by the iOS Simulator, the instruments command-line tool included with the Xcode 5 developer tools (build 5A11314m) prints a large number of what appear to be internal log messages.

Steps to Reproduce:

1. Open the simulator and set its version to 7.0 (11A4372q).
2. Make sure that no other SDKs are installed. It suffices to temporarily remove iPhoneSimulator[5.1, 6.1, etc].sdk from /Applications/Xcode5-DP.app/Contents/Developer/Platforms/iPhoneSimulator.platform/Developer/SDKs/. Note that you must do this *after* the Simulator has been opened and its version set, otherwise the Simulator will crash.
3. Execute `sh RunUIAutomationTests.sh` from the root directory of the attached project.

Expected Results:

instruments should run the `LogIn.js` script using the Automation trace template (as directed by `RunUIAutomationTests.sh`, only logging messages beginning with "Debug: ".

Actual Results:

The tool prints, in order:
* a number of lines of the format "pid 2 = <pid>"
* "Its me!!!" (sic)
* a number of lines of the format "CLTilesManagerClient: ..."
* and then, before every "Debug: " message logged, a message "_msgObj is __NSCFString".

Regression:

Yes: following the steps to reproduce using the Xcode 4.6.2 (4H1003) developer tools (i.e instruments 4.5 (46000)) yields the expected results.

Notes:

This issue is also reproducible, with slightly different messages, using the iphonesimulator5.1 and 6.1 SDKs, but it is not fully apparent when using the 6.1 SDK due to http://openradar.appspot.com/radar?id=3115412.

Comments

Still an issue with 5.1 (5B130a), instruments 5.1 (55044)

instruments' output still begins with:

2013-07-30 12:43:58.760 ScriptAgent[68213:2c07] CLTilesManagerClient: initialize, sSharedTilesManagerClient 2013-07-30 12:43:58.761 ScriptAgent[68213:2c07] CLTilesManagerClient: init 2013-07-30 12:43:58.761 ScriptAgent[68213:2c07] CLTilesManagerClient: reconnecting, 0xa159bc0

By jeff at March 11, 2014, 2:20 a.m. (reply...)

Still an issue with Xcode 5.1 beta 3

This issue has gotten better, but is not yet fully resolved with iOS 7 beta 4 (5A11365j). If you run a UIAutomation script, at the top of instruments' output, you'll see a series of messages like the following:

2013-07-30 12:43:58.760 ScriptAgent[68213:2c07] CLTilesManagerClient: initialize, sSharedTilesManagerClient 2013-07-30 12:43:58.761 ScriptAgent[68213:2c07] CLTilesManagerClient: init 2013-07-30 12:43:58.761 ScriptAgent[68213:2c07] CLTilesManagerClient: reconnecting, 0xa159bc0

Which should not be logged.

By jeff at Jan. 8, 2014, 11:45 p.m. (reply...)

Please note: Reports posted here will not necessarily be seen by Apple. All problems should be submitted at bugreport.apple.com before they are posted here. Please only post information for Radars that you have filed yourself, and please do not include Apple confidential information in your posts. Thank you!