more debug messages

[kolive]

I am running vuze through the console. 
It should be the latest version.

Every 2 minutes, I get a debug message.
Everything still works (uploading and downloading),
but I don't understand what the messages are telling me.

I attach a pair below. They keep coming like clockwork every 2 min.

Thanks,

DEBUG::Sun Apr 26 18:19:27 CDT 2015::org.gudy.azureus2.core3.util.DebugLight::printStackTrace::35:  java.lang.NullPointerException        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.checkStalls(RatingsUpdater.java:267)        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.access$6(RatingsUpdater.java:264)        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater$1$1.perform(RatingsUpdater.java:192)        at org.gudy.azureus2.core3.util.TimerEventPeriodic.perform(TimerEventPeriodic.java:105)        at org.gudy.azureus2.core3.util.TimerEvent.runSupport(TimerEvent.java:133)        at org.gudy.azureus2.core3.util.AERunnable.run(AERunnable.java:35)        at org.gudy.azureus2.core3.util.ThreadPool.runIt(ThreadPool.java:328)        at org.gudy.azureus2.core3.util.ThreadPool$threadPoolWorker.run(ThreadPool.java:717)        at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294) 

DEBUG::Sun Apr 26 18:21:27 CDT 2015::org.gudy.azureus2.core3.util.DebugLight::printStackTrace::35:  java.lang.NullPointerException        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.checkStalls(RatingsUpdater.java:267)        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.access$6(RatingsUpdater.java:264)        at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater$1$1.perform(RatingsUpdater.java:192)        at org.gudy.azureus2.core3.util.TimerEventPeriodic.perform(TimerEventPeriodic.java:105)        at org.gudy.azureus2.core3.util.TimerEvent.runSupport(TimerEvent.java:133)        at org.gudy.azureus2.core3.util.AERunnable.run(AERunnable.java:35)        at org.gudy.azureus2.core3.util.ThreadPool.runIt(ThreadPool.java:328)        at org.gudy.azureus2.core3.util.ThreadPool$threadPoolWorker.run(ThreadPool.java:717)        at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
 

[parg]

Fix will be in next beta (B23) - thanks!

[kolive]

B23 didn't help.

Now I get many more message on start up and the message every 2 min keep coming along
with others.

sorry for the long attachments, but maybe they mean something to you.


at startup:
DEBUG::Mon Apr 27 19:33:15 CDT 2015::com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta$5::runSupport::1167:
run (AsyncDispatcher.java:160), run (AEThread2.java:294)
java.lang.Exception: org.gudy.azureus2.plugins.ipc.IPCException: org.gudy.azureus2.plugins.ipc.IPCException: Failed to create message handler
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta$ChatInstance.bind(BuddyPluginBeta.java:2702)
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta$ChatInstance.access$900(BuddyPluginBeta.java:2091)
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta.getChat(BuddyPluginBeta.java:1880)
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta.getChat(BuddyPluginBeta.java:1816)
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta$5.runSupport(BuddyPluginBeta.java:1160)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
Caused by: org.gudy.azureus2.plugins.ipc.IPCException: org.gudy.azureus2.plugins.ipc.IPCException: Failed to create message handler
at org.gudy.azureus2.pluginsimpl.local.ipc.IPCInterfaceImpl.invoke(IPCInterfaceImpl.java:147)
at com.aelitis.azureus.plugins.net.buddy.BuddyPluginBeta$ChatInstance.bind(BuddyPluginBeta.java:2684)
... 6 more
Caused by: org.gudy.azureus2.plugins.ipc.IPCException: Failed to create message handler
at com.vuze.plugins.azmsgsync.MsgSyncPlugin.getSyncHandler(MsgSyncPlugin.java:1225)
at com.vuze.plugins.azmsgsync.MsgSyncPlugin.getMessageHandler(MsgSyncPlugin.java:779)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.gudy.azureus2.pluginsimpl.local.ipc.IPCInterfaceImpl.invoke(IPCInterfaceImpl.java:135)
... 7 more
Caused by: java.lang.RuntimeException: DHT isn't enabled
at com.aelitis.azureus.plugins.dht.DHTPlugin.getLocalAddress(DHTPlugin.java:1873)
at com.vuze.plugins.azmsgsync.MsgSyncHandler.init(MsgSyncHandler.java:583)
at com.vuze.plugins.azmsgsync.MsgSyncHandler.<init>(MsgSyncHandler.java:375)
at com.vuze.plugins.azmsgsync.MsgSyncHandler.<init>(MsgSyncHandler.java:402)
at com.vuze.plugins.azmsgsync.MsgSyncPlugin.getSyncHandler(MsgSyncPlugin.java:1212)
... 13 more


then:
DEBUG::Mon Apr 27 19:33:18 CDT 2015 Select operation looks broken, trying workaround
DEBUG::Mon Apr 27 19:33:19 CDT 2015 Select operation looks broken, trying workaround
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
DEBUG::Mon Apr 27 19:35:15 CDT 2015::org.gudy.azureus2.core3.util.DebugLight::printStackTrace::35:
java.lang.NullPointerException
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.checkStalls(RatingsUpdater.java:267)
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.access$6(RatingsUpdater.java:264)
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater$1$1.perform(RatingsUpdater.java:192)
at org.gudy.azureus2.core3.util.TimerEventPeriodic.perform(TimerEventPeriodic.java:105)
at org.gudy.azureus2.core3.util.TimerEvent.runSupport(TimerEvent.java:133)
at org.gudy.azureus2.core3.util.AERunnable.run(AERunnable.java:35)
at org.gudy.azureus2.core3.util.ThreadPool.runIt(ThreadPool.java:328)
at org.gudy.azureus2.core3.util.ThreadPool$threadPoolWorker.run(ThreadPool.java:717)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)

java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)
DEBUG::Mon Apr 27 19:37:15 CDT 2015::org.gudy.azureus2.core3.util.DebugLight::printStackTrace::35:
java.lang.NullPointerException
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.checkStalls(RatingsUpdater.java:267)
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater.access$6(RatingsUpdater.java:264)
at com.aelitis.azureus.plugins.rating.updater.RatingsUpdater$1$1.perform(RatingsUpdater.java:192)
at org.gudy.azureus2.core3.util.TimerEventPeriodic.perform(TimerEventPeriodic.java:105)
at org.gudy.azureus2.core3.util.TimerEvent.runSupport(TimerEvent.java:133)
at org.gudy.azureus2.core3.util.AERunnable.run(AERunnable.java:35)
at org.gudy.azureus2.core3.util.ThreadPool.runIt(ThreadPool.java:328)
at org.gudy.azureus2.core3.util.ThreadPool$threadPoolWorker.run(ThreadPool.java:717)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)

and they keep coming.

[parg]

Hmm, that's not good! B24 should improve things a bit - not sure why the original one is still there though. You could disable the ratings plugin I guess. Any reason why you have the DHT disabled?

[kolive]

Thanks for helping.

I will look for B24.

I tried to disable rating, but it seems to come back when I restart.
I tried this in the GUI.
Also in the GUI, when I look at the DHT plugin, it says enabled.

Is it different when I run from the console?

But I don't see the ratings messages, so maybe that's ok.
Same stuff as in the last message at startup, and

lots of these:
java.lang.NullPointerException
at com.aelitis.azureus.core.content.RelatedContentSearcher$8.runSupport(RelatedContentSearcher.java:1955)
at org.gudy.azureus2.core3.util.AsyncDispatcher$1.run(AsyncDispatcher.java:160)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)

and these:
DEBUG::Mon Apr 27 22:43:16 CDT 2015::org.gudy.azureus2.core3.util.AEThread2$threadWrapper::run::294:
readSelectorLoop() EXCEPTION:


I'll let you know what happens after I install B24.

Thanks again

[kolive]

Well B24 certainly helped. Gone are all the messages posted above at start-up.

It is now as it used to be with the following:
SystemProperties: determineApplicationName - can't determine application name from Azureus2.jar
###
Warning: Core not instantiated through a PrimaryClassloader, this can lead to restricted functionality or bugs in future versions
###
OSXAccess_10.5 v1.11 Load complete!

But these have been there for a long time, and I ignore them.

At the end of start-up, I get
DEBUG::Thu Apr 30 07:21:49 CDT 2015 Select operation looks broken, trying workaround
DEBUG::Thu Apr 30 07:21:49 CDT 2015 Select operation looks broken, trying workaround
Warning - No client config file /Users/keith/Library/Application Support/Vuze/plugins/azneti2phelper/clients.config
Warning - No client apps or router console configured - we are just a router

The 2 debug messages have also been there a long time, and I ignore them as well.
The 2 warnings are new, but they seem harmless.

And best of all, the 2 minute arrival of debug messages are gone!

Thank you for your help and support!

[parg]

Excellent!

[kolive]

Maybe I spoke too soon.

There still some messages that come at irregular and much longer intervals:

DEBUG::Thu Apr 30 17:33:22 CDT 2015::org.gudy.azureus2.core3.util.AEThread2$threadWrapper::run::294:
readSelectorLoop() EXCEPTION:

java.nio.channels.CancelledKeyException
at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
at com.aelitis.azureus.core.networkmanager.impl.tcp.VirtualChannelSelectorImpl.select(VirtualChannelSelectorImpl.java:866)
at com.aelitis.azureus.core.networkmanager.VirtualChannelSelector.select(VirtualChannelSelector.java:349)
at com.aelitis.azureus.core.networkmanager.impl.tcp.TCPNetworkManager$4.run(TCPNetworkManager.java:193)
at org.gudy.azureus2.core3.util.AEThread2$threadWrapper.run(AEThread2.java:294)

maybe its nothing, and no where near as bad as getting them every 2 minutes. These come
(and they seem to be identical) maybe on average once an hour.

I thought you'd like to know.

Thanks again.