[TIMOB-23975] Android: Logging outputs tons of noisy, meaningless messages (Regression)

GitHub Issue	n/a
Type	Bug
Priority	Critical
Status	Closed
Resolution	Duplicate
Resolution Date	2017-01-18T18:38:39.000+0000
Affected Version/s	Release 5.4.0, Release 5.5.0
Fix Version/s	n/a
Components	Android
Labels	n/a
Reporter	Tim Poulsen (ACV)
Assignee	Eric Merriman
Created	2016-09-23T14:04:56.000+0000
Updated	2017-03-23T22:47:09.000+0000

Description

Working from the command line, with appc run -p android -T device, console level logging is incredibly noisy and near-impossible to pull good info from. Starting with roughly the 5.x line of tooling, the console logs are filled with hundreds, often thousands of these lines: [WARN] DisplayListCanvas: DisplayListCanvas is started on unbinded RenderNode (without mOwningView) Of course, it would be best to fix the underlying Android issue that's causing this warning. However, the logger should at least filter them out since it has nothing to do with my app but with the Ti framework. With the tooling versions listed in the Environment section, routine Android system-level log messages are no longer filtered out. Thus, I now have to sift through tons of distracting log messages like the following (which is just a small sample of the multitude of system messages now output)

[WARN]  V/TaskManager:TaskManagerRemoteService(10661): case SEND_BROADCAST_ACTIVE_APPS
[WARN]  I/TaskManager:PackageInfo(10661): getRunningAppPackageList()
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Hidden Pkg] - pkg:com.android.contacts
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Hidden Pkg] - pkg:com.android.systemui
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.bluetooth
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.dropbox.android
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.gsamlabs.bbm
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.app.bluetoothtest
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.qualcomm.atfwd
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.facebook.katana
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.sdk.samsunglink
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.acmeaom.android.myradar
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.daemonapp
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.qualcomm.embms
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.cequint.ecid
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.google.android.gms
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.app.taskmanager
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.dsi.ant.server
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.google.android.apps.googlevoice
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.imsservice
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.exchange
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.app.samsungapps
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.sec.android.application.csc
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.accuweather.paid.android
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.spp.push
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.sve
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.app.memo
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.InputEventApp
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.pagebuddynotisvc
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.MtpApplication
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.telecomsys.directedsms.android.SCG
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.fmm
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.server.telecom
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:org.npr.android.news
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.stk
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:android
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.providers.media
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:org.mariotaku.twidere
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.ipsec.service
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.providers.context
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.sec.android.app.shealth
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.asurion.android.verizon.vms
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.android.beaconmanager
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.samsung.accessory
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.vzw.hss.widgets.infozone
[WARN]  I/TaskManager:PackageInfo(10661): RunningApp[Service] - pkg:com.android.phone
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): calcCPUUsage() packageName:com.acvauctions.demo usage:57.568977
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): calcCPUUsage() packageName:com.android.chrome usage:0.0
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): calcCPUUsage() packageName:com.google.android.apps.messaging usage:0.0
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): calcCPUUsage() packageName:com.facebook.orca usage:0.0
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): PrevCount : 4 , RunningCount : 4
[WARN]  V/TaskManager:TaskManagerRemoteService(10661): Sending new running_count : 4

Comments

Sharif AbuDarda 2016-09-23

What about simulator run? Is is the same log shows in there?
Tim Poulsen (ACV) 2016-09-23

Since I need access to the camera, push notifications, etc. for our app, I don't use the simulator for testing. So, I'm afraid I don't know.
Hans Knöchel 2016-10-02

This seems to happen since Titanium SDK 5.4.0 like stated in AC-4522. It's likely caused by TIMOB-23975. [~cwilliams] you may have more context here.
Tim Poulsen (ACV) 2016-12-08

I finally have had the chance to work on updating our app to Ti 6.0 (Appc CLI, plus SDK). The logs are just as noisy as they were with 5.x. But worse, you no longer tag each app-related log message with TiAPI so tricks like adb logcat | grep TiAPI no longer work. You don't even output the process ID for use in filtering like that. I suppose you're doing something to make Studio work but I'm afraid I don't use it. Maybe you could share your tricks?

Tim Poulsen (ACV) 2017-01-18

Logging is greatly improved in SDK 6.1.0.v20170118081704. Thanks! I still see some (what I think are) non-app messages in the log. The volume of these is not all that bad. So, it wouldn't be terrible if they couldn't be suppressed. For the record, I'm seeing:

   [INFO]  art: Background sticky concurrent mark sweep GC freed 85617(6MB) AllocSpace objects, 48(1260KB) LOS objects, 20%% free, 32MB/40MB, paused 791us total 251.101ms
   [INFO]  I/System.out: (HTTPLog)-Static: isSBSettingEnabled false
   [INFO]  Timeline: Timeline: Activity_idle id: android.os.BinderProxy@eec341f time:69011706
   [INFO]  I/InputDispatcher(  807): Delivering touch to : action: 0x0, toolType: 1
   [INFO]  I/InputDispatcher(  807): Delivering touch to : action: 0x1, toolType: 1
   [INFO]  APSAnalyticsService: Analytics Service Started
   [INFO]  APSAnalyticsService: Stopping Analytics Service
   [WARN]  IInputConnectionWrapper: getExtractedText on inactive InputConnection
   [WARN]  IInputConnectionWrapper: getTextBeforeCursor on inactive InputConnection
   [WARN]  IInputConnectionWrapper: getSelectedText on inactive InputConnection
   [WARN]  IInputConnectionWrapper: getTextAfterCursor on inactive InputConnection

This following message is repeated frequently (dozens of times per minute)

   [INFO]  I/System.out: (HTTPLog)-Static: isSBSettingEnabled false

Hans Knöchel 2017-01-18

Resolving as Duplicate of TIMOB-24311, thanks Tim for checking!
Hans Knöchel 2017-01-18

As a follow-up for the outstanding logs observed by Tim, this seems to be a common Android thing ([Link](http://stackoverflow.com/questions/30360823/how-do-i-fix-whatever-is-causing-this-to-flood-my-logcat-i-system-out-httplog)), so we may add those parts to the regex as well to mute them. [~michael] since you did the last fix, is this something you could think of?
Michael Gangolf 2017-01-18

Already testing :)
Lee Morris 2017-03-23

Closing ticket as Duplicate with reference to the above comments.

JSON Source