urbanairship / android-library

Urban Airship Android SDK
Other
112 stars 123 forks source link

Excessive log messages after upgrade to either 8.8 or 8.9 #26

Closed KennyGoers closed 7 years ago

KennyGoers commented 7 years ago

I just upgraded from 8.5.1 to 8.8.4 (also tried 8.9.0), suddenly I'm getting a flood of the following messages in the log:

Thoughts?

10-03 11:33:39.428 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.431 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.514 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.517 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.585 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.590 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.698 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.702 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.784 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.786 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.878 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.881 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:39.985 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:39.988 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:40.082 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:40.085 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:40.177 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:40.182 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:40.271 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:40.273 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:40.377 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:40.380 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:40.946 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:40.947 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.085 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.088 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.458 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.461 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.544 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.545 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.639 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.641 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.736 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.738 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.819 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.821 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:41.909 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:41.911 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.008 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.012 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.123 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.125 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.207 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.209 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.292 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.294 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.371 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.374 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.453 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.457 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.552 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.554 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.634 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.637 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.726 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.728 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.803 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.806 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.891 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.893 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:43.967 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:43.969 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.062 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.064 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.166 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.168 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.248 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.251 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.331 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.332 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.420 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.424 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.511 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.514 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.611 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.613 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.720 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.723 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.796 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.798 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.873 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.876 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:44.956 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:44.959 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.066 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.069 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.140 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.142 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.221 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.223 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.332 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.334 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.461 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.463 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.537 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.540 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.638 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.641 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.796 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.797 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.904 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.907 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:45.977 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:45.979 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.062 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.064 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.163 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.165 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.273 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.275 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.369 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.372 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.453 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.455 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED 10-03 11:33:46.547 5142-5264/? I/Zubie-QA - UALib: Channel registration succeeded with status: 200 10-03 11:33:46.550 5142-5142/? D/Zubie-QA - UALib: UrbanAirshipMessageReceiver - Received intent with action: com.urbanairship.push.CHANNEL_UPDATED

rlepinski commented 7 years ago

You can change the log level through airship config options. developmentLogLevel = INFO. However I am concerned why your channel registration is updating constantly. Can you actually set the log level to VERBOSE and report back with the logs?

KennyGoers commented 7 years ago

I can, this doesn't happen when I downgrade back to 8.5.1. Another point is that this is in the emulator, but that hasn't been an issue.

KennyGoers commented 7 years ago

I just checked, that is VERBOSE, I enable VERBOSE for non-production builds.

rlepinski commented 7 years ago

@KennyGoers Could you provide me the entire log output instead of filtered logs? Make sure not to post any secrets.

KennyGoers commented 7 years ago

I'll email to you

rlepinski commented 7 years ago

Cool, once I get the logs I will look into it immediately. I am unable to reproduce the issue on my machine so I am very curious what is going on in that emulator.

KennyGoers commented 7 years ago

Well now I can't get it to happen again, was happening in two different emulators (Android 25 and 26), possibly data or something else from moving back and forth between versions (8.5.1 -> 8.9.0 -> 8.5.1 -> 8.9.0) ? I'll continue to monitor and watch the logs, but this has happened to me several times, just hadn't taken the time to report as it didn't have a perceivable result other than flooding the logs.

KennyGoers commented 7 years ago

I have logs for you, I got it to happen again. Can you give me your email address again?

rlepinski commented 7 years ago

Awesome!

rlepinski commented 7 years ago

@KennyGoers It looks like the logs are DEBUG not VERBOSE. Could you double check you are not filtering your logcat for DEBUG and above? You should see message that match V/Zubie-QA - UALib:. Also how are you registering the UrbanAirshipMessageReceiver class?

rlepinski commented 7 years ago

@KennyGoers Could your provide what build tools, android gradle plugin, and any other build related flags you are using?

rlepinski commented 7 years ago

@KennyGoers We just released 8.9.1 with a potential fix to your issue. Could you try it out and report back? Thanks

KennyGoers commented 7 years ago

I will, thanks. I was out all day so just saw your message.

On Mon, Oct 9, 2017 at 5:38 PM Ryan Lepinski notifications@github.com wrote:

@KennyGoers https://github.com/kennygoers We just released 8.9.1 with a potential fix to your issue. Could you try it out and report back? Thanks

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/urbanairship/android-library/issues/26#issuecomment-335309040, or mute the thread https://github.com/notifications/unsubscribe-auth/AAaOPOFvkG5ggym9caW5KDBCKEkqe1gqks5sqqBggaJpZM4PsbDz .

rlepinski commented 7 years ago

@KennyGoers did 8.9.1 resolve your issue? We had two patch release since 8.9.1 and we would recommend updated to 8.9.3.

rlepinski commented 7 years ago

Closing for now