On Thu, Oct 16, 2003 at 02:32:07AM -0700, Martin Stone Davis wrote: > Using WinXP, Java 1.4.2-b28, frost-wot090603, Freenet build 6251: > > Oct 16, 2003 1:58:10 AM (freenet.node.rt.StandardNodeEstimator, > QThread-11694, NORMAL): Not logging transfer rate because size=1025, > transferTime=0 > java.lang.Exception: debug > at > freenet.node.rt.StandardNodeEstimator.transferSucceeded(StandardNodeEstimator.java:251) > at freenet.node.rt.NGRouting.transferSucceeded(NGRouting.java:167) > at > freenet.node.states.request.TransferReply.receivedMessage(TransferReply.java:180) > at sun.reflect.GeneratedMethodAccessor14.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) > at java.lang.reflect.Method.invoke(Unknown Source) > at freenet.node.State.received(State.java:126) > at freenet.node.StateChain.received(StateChain.java:195) > at freenet.node.StateChain.received(StateChain.java:71) > at > freenet.node.StandardMessageHandler$Ticket.run(StandardMessageHandler.java:234) > at > freenet.node.StandardMessageHandler$Ticket.received(StandardMessageHandler.java:172) > at > freenet.node.StandardMessageHandler$Ticket.access$100(StandardMessageHandler.java:124) > at > freenet.node.StandardMessageHandler.handle(StandardMessageHandler.java:72) > at freenet.Ticker$Event.run(Ticker.java:323) > at freenet.thread.QThreadFactory$QThread.run(QThreadFactory.java:234)
This shouldn't get logged at normal unless the key is a bit bigger. Changed in local tree. > > ... > > Oct 16, 2003 2:00:39 AM (freenet.client.AutoRequester, QThread-11766, > ERROR): Got StateReachedEvent (State FAILED reached.) with > currentRequestProcess == null! for freenet.client.AutoRequester:(not > requesting)():freenet:SSK@<snip> > java.lang.Exception: debug > at > freenet.client.AutoRequester.onReachedState(AutoRequester.java:811) > at freenet.client.AutoRequester.access$100(AutoRequester.java:26) > at > freenet.client.AutoRequester$AutoListener.onDone(AutoRequester.java:859) > at > freenet.client.listeners.DoneListener.receive(DoneListener.java:60) > at > freenet.client.AutoRequester$AutoListener.receive(AutoRequester.java:855) > at > freenet.client.SimpleEventProducer.produceEvent(SimpleEventProducer.java:55) > at > freenet.client.InternalClient$InternalFeedbackToken.unlockedProduceEvent(InternalClient.java:192) > at > freenet.client.InternalClient$InternalGetToken.dataNotFound(InternalClient.java:331) > at > freenet.node.states.request.DataPending.dataNotFound(DataPending.java:171) > at > freenet.node.states.request.DataPending.receivedMessage(DataPending.java:71) > at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) > at java.lang.reflect.Method.invoke(Unknown Source) > at freenet.node.State.received(State.java:126) > at freenet.node.StateChain.received(StateChain.java:195) > at freenet.node.StateChain.received(StateChain.java:71) > at > freenet.node.StandardMessageHandler$Ticket.run(StandardMessageHandler.java:234) > at > freenet.node.StandardMessageHandler$Ticket.received(StandardMessageHandler.java:172) > at > freenet.node.StandardMessageHandler$Ticket.access$100(StandardMessageHandler.java:124) > at > freenet.node.StandardMessageHandler.handle(StandardMessageHandler.java:72) > at freenet.Ticker$Event.run(Ticker.java:323) > at freenet.thread.QThreadFactory$QThread.run(QThreadFactory.java:234) No idea. What were you doing at the time? Fproxy? Would definitely need to reproduce locally (well, probably). > > ... > > Oct 16, 2003 2:01:43 AM (freenet.support.BlockingQueue, Ticker immediate > execution thread, NORMAL): Waited more than 200ms to dequeue, 6 in > queue, 1406 millis since enqueued last item, 248877 maximum waits so far > - could indicate serious JVM bug. Please report to > [EMAIL PROTECTED] along with JVM and OS/kernel. > Waited more than 200ms to dequeue, 6 in queue, 1406 millis since > enqueued last item, 248877 maximum waits so far - could indicate serious > JVM bug. Please report to [EMAIL PROTECTED] along with JVM and > OS/kernel. This happens frequently, it may be a scheduling glitch... I'm not sure what to do about it... > Oct 16, 2003 2:01:55 AM (freenet.node.states.request.TransferReply, > QThread-11622, NORMAL): Failed to send data with CB 0x83 > (CB_RECV_CONN_DIED), for freenet.node.states.request.TransferReply: > key=<snip>, hopsToLive=28, id=<snip>, > [EMAIL PROTECTED] > (<snip>,request),ft=freenet.node.states.FCP.ClientGetToken@<snip>, > routedTime=1066290877022, replyTime=1066291015303, outwardSender=null Normal sadly. If it happens a lot something is probably going on. > Oct 16, 2003 2:02:15 AM (freenet.client.AutoRequester, QThread-11744, > ERROR): Got StateReachedEvent (State FAILED reached.) with > currentRequestProcess == null! for freenet.client.AutoRequester:(not > requesting)():freenet:SSK@<snip> > java.lang.Exception: debug > at > freenet.client.AutoRequester.onReachedState(AutoRequester.java:811) > at freenet.client.AutoRequester.access$100(AutoRequester.java:26) > at > freenet.client.AutoRequester$AutoListener.onDone(AutoRequester.java:859) > at > freenet.client.listeners.DoneListener.receive(DoneListener.java:60) > at > freenet.client.AutoRequester$AutoListener.receive(AutoRequester.java:855) > at > freenet.client.SimpleEventProducer.produceEvent(SimpleEventProducer.java:55) > at > freenet.client.InternalClient$InternalFeedbackToken.unlockedProduceEvent(InternalClient.java:192) > at > freenet.client.InternalClient$InternalGetToken.dataNotFound(InternalClient.java:331) > at > freenet.node.states.request.DataPending.dataNotFound(DataPending.java:171) > at > freenet.node.states.request.DataPending.receivedMessage(DataPending.java:71) > at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) > at java.lang.reflect.Method.invoke(Unknown Source) > at freenet.node.State.received(State.java:126) > at freenet.node.StateChain.received(StateChain.java:195) > at freenet.node.StateChain.received(StateChain.java:71) > at > freenet.node.StandardMessageHandler$Ticket.run(StandardMessageHandler.java:234) > at > freenet.node.StandardMessageHandler$Ticket.received(StandardMessageHandler.java:172) > at > freenet.node.StandardMessageHandler$Ticket.access$100(StandardMessageHandler.java:124) > at > freenet.node.StandardMessageHandler.handle(StandardMessageHandler.java:72) > at freenet.Ticker$Event.run(Ticker.java:323) > at freenet.thread.QThreadFactory$QThread.run(QThreadFactory.java:234) Duplicate of above. > > ... > > Oct 16, 2003 2:17:41 AM (freenet.node.states.request.TransferReply, > QThread-11907, NORMAL): Upstream node connection died for > freenet.node.states.request.TransferReply: key=<snip>, hopsToLive=28, > id=<snip>, [EMAIL PROTECTED] > (<snip>,request),[EMAIL PROTECTED]@<snip>, > routedTime=<snip>, replyTime=1066291961444, outwardSender=null > Oct 16, 2003 2:18:00 AM (freenet.client.AutoRequester, QThread-11861, > ERROR): Got StateReachedEvent (State FAILED reached.) with > currentRequestProcess == null! for freenet.client.AutoRequester:(not > requesting)():freenet:SSK@<snip> > java.lang.Exception: debug > at > freenet.client.AutoRequester.onReachedState(AutoRequester.java:811) > at freenet.client.AutoRequester.access$100(AutoRequester.java:26) > at > freenet.client.AutoRequester$AutoListener.onDone(AutoRequester.java:859) > at > freenet.client.listeners.DoneListener.receive(DoneListener.java:60) > at > freenet.client.AutoRequester$AutoListener.receive(AutoRequester.java:855) > at > freenet.client.SimpleEventProducer.produceEvent(SimpleEventProducer.java:55) > at > freenet.client.InternalClient$InternalFeedbackToken.unlockedProduceEvent(InternalClient.java:192) > at > freenet.client.InternalClient$InternalGetToken.dataNotFound(InternalClient.java:331) > at > freenet.node.states.request.DataPending.dataNotFound(DataPending.java:171) > at > freenet.node.states.request.DataPending.receivedMessage(DataPending.java:71) > at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) > at java.lang.reflect.Method.invoke(Unknown Source) > at freenet.node.State.received(State.java:126) > at freenet.node.StateChain.received(StateChain.java:195) > at freenet.node.StateChain.received(StateChain.java:71) > at > freenet.node.StandardMessageHandler$Ticket.run(StandardMessageHandler.java:234) > at > freenet.node.StandardMessageHandler$Ticket.received(StandardMessageHandler.java:172) > at > freenet.node.StandardMessageHandler$Ticket.access$100(StandardMessageHandler.java:124) > at > freenet.node.StandardMessageHandler.handle(StandardMessageHandler.java:72) > at freenet.Ticker$Event.run(Ticker.java:323) > at freenet.thread.QThreadFactory$QThread.run(QThreadFactory.java:234) > > ... > > Oct 16, 2003 2:27:41 AM (freenet.node.rt.StandardNodeEstimator, > QThread-12001, NORMAL): Not logging transfer rate because size=1025, > transferTime=0 > java.lang.Exception: debug > at > freenet.node.rt.StandardNodeEstimator.transferSucceeded(StandardNodeEstimator.java:251) > at freenet.node.rt.NGRouting.transferSucceeded(NGRouting.java:167) > at > freenet.node.states.request.TransferReply.receivedMessage(TransferReply.java:180) > at sun.reflect.GeneratedMethodAccessor14.invoke(Unknown Source) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) > at java.lang.reflect.Method.invoke(Unknown Source) > at freenet.node.State.received(State.java:126) > at freenet.node.StateChain.received(StateChain.java:195) > at freenet.node.StateChain.received(StateChain.java:71) > at > freenet.node.StandardMessageHandler$Ticket.run(StandardMessageHandler.java:234) > at > freenet.node.StandardMessageHandler$Ticket.received(StandardMessageHandler.java:172) > at > freenet.node.StandardMessageHandler$Ticket.access$100(StandardMessageHandler.java:124) > at > freenet.node.StandardMessageHandler.handle(StandardMessageHandler.java:72) > at freenet.Ticker$Event.run(Ticker.java:323) > at freenet.thread.QThreadFactory$QThread.run(QThreadFactory.java:234) > > ... > > Oct 16, 2003 2:31:56 AM (freenet.node.states.request.TransferReply, > QThread-12035, NORMAL): Failed to send data with CB 0x83 > (CB_RECV_CONN_DIED), for freenet.node.states.request.TransferReply: > key=<snip>, hopsToLive=1, id=<snip>, > [EMAIL PROTECTED] > (<snip>,request),ft=freenet.client.InternalClient$InternalGetToken:[EMAIL > PROTECTED],key=freenet:CHK@<snip>,skipBytes=0@<snip>, > routedTime=1066292595475, replyTime=1066292614897, outwardSender=null Also unfortunately normal. May be mitigated by multiplexing, if it's a real TCP timeout, or they just have too many connections receiving... may be mitigated by routing working better so the transfers aren't so slow, and by recent changes to bwlimiting rejecting behaviour. Could also be caused by data corruption... -- Matthew J Toseland - [EMAIL PROTECTED] Freenet Project Official Codemonkey - http://freenetproject.org/ ICTHUS - Nothing is impossible. Our Boss says so.
signature.asc
Description: Digital signature
_______________________________________________ Devl mailing list [EMAIL PROTECTED] http://dodo.freenetproject.org/cgi-bin/mailman/listinfo/devl
