Hi,
Inserts with htl>1 fail. Most but not all connections to 19114 in netstat
are outgoing at any one time. I have to go out now but if you want me to try
anything else let me know and I'll do it when I get back (Tonight probably)
system details:
RedHat 6.0 (ish) on x86. Blackdown Java 1.1.2 pre. Java environment is OK (I
do JServ java development on it). Freenet: latest CVS as of about 1330
today.
As far as timeouts are concerned: I have had to up my node connection
timeouts before I see any outgoing freenet connections in netstat.
Degs,
Hope it is OK to post this uncompressed there isn't that much of it:
Trace with htl=1
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'tickerTime'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'connectTimeout'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'connectionTimeout'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'handshakeTimeout'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'handshakeLife'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'hopTimeExpected'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'hopTimeDeviation'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'maximumConnectionThreads'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'bufferSize'
Aug 26, 2000 2:10:32 PM:Freenet.Params:-1262304161:Debug:No value specified
for parameter 'keyTypes'
Aug 26, 2000 2:10:33 PM:Freenet.transport.tcpConnection:1093059672:Debug:not
throttling local connection
Aug 26, 2000 2:10:33
PM:Freenet.client.ClientCore:1336591448:Debug:Connection between:
tcp/127.0.0.1:1363 and tcp/127.0.0.1:19114
Aug 26, 2000 2:10:33 PM:Freenet.ConnectionHandler:1213383768:Debug:New
connectionhandler with tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Sending a
message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:af18ec5725d47207 -
HandshakeRequest -> tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34
PM:Freenet.presentation.FNPRawMessage:1681835096:Debug:Reading message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Minor:Rawmessage:
HandshakeReply
{Source=tcp/127.0.0.1:19114,KeepAlive=true,DataLength=0,{Depth=1,Build=146,R
evision=1.51,UniqueID=af18ec5725d47207,Version=1.29,HopsToLive=1,}}
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:af18ec5725d47207 -
HandshakeReply <- tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Message:
Src:null htl:1 depth:1 id:af18ec5725d47207
type:Freenet.message.HandshakeReply
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Got on
tcp/127.0.0.1:1365 from tcp/127.0.0.1:19114
Handled: Src:tcp/127.0.0.1:19114 htl:1 depth:1 id:af18ec5725d47207
type:Freenet.message.HandshakeReply
Aug 26, 2000 2:10:34
PM:Freenet.message.HandshakeReply:1828897880:Debug:Setting handshake from
tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34
PM:Freenet.StandardHandshakeHandler:1594803288:Debug:Done waiting for
handshake, returning true
Aug 26, 2000 2:10:34
PM:Freenet.client.events.StateReachedEvent:-1665219489:Normal:State
"PREPARED" reached.
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Sending a
message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:d282a70a34253d56 -
InsertRequest -> tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.client.events.SendEvent:-1981365153:Normal:A
message was sent to tcp/127.0.0.1:19114.
Aug 26, 2000 2:10:34
PM:Freenet.client.events.StateReachedEvent:-1959082913:Normal:State
"REQUESTING" reached.
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Finished
with message
Aug 26, 2000 2:10:34
PM:Freenet.presentation.FNPRawMessage:-695810977:Debug:Reading message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Minor:Rawmessage:
InsertReply
{Source=tcp/127.0.0.1:19114,KeepAlive=true,DataLength=0,{Depth=0,UniqueID=d2
82a70a34253d56,HopsToLive=25,}}
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:d282a70a34253d56 -
InsertReply <- tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Message:
Src:null htl:37 depth:0 id:d282a70a34253d56 type:Freenet.message.InsertReply
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Got on
tcp/127.0.0.1:1365 from tcp/127.0.0.1:19114
Handled: Src:tcp/127.0.0.1:19114 htl:37 depth:0 id:d282a70a34253d56
type:Freenet.message.InsertReply
Aug 26, 2000 2:10:34
PM:Freenet.client.events.ReceiveEvent:-429472673:Normal:A message was
received from tcp/127.0.0.1:19114.
Aug 26, 2000 2:10:34
PM:Freenet.client.events.StateReachedEvent:340968543:Normal:State
"TRANSFERING" reached.
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Sending a
message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:d282a70a34253d56 -
DataInsert -> tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.client.events.SendEvent:458933343:Normal:A
message was sent to tcp/127.0.0.1:19114.
Aug 26, 2000 2:10:34 PM:Freenet.Conduit:455787615:Debug:1- Conduit starting,
reading 38 bytes.
Aug 26, 2000 2:10:34
PM:Freenet.client.EventInputStream$TransferedEvent:15909983:Normal:37 bytes
read.
Aug 26, 2000 2:10:34
PM:Freenet.client.events.TransferCompletedEvent:255509599:Normal:Transfer
ended with 38 bytes read.
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Sending a
message
Aug 26, 2000 2:10:34
PM:Freenet.ConnectionHandler:1213383768:Normal:d282a70a34253d56 -
StoreData -> tcp/127.0.0.1:19114
Aug 26, 2000 2:10:34 PM:Freenet.client.events.SendEvent:842187871:Normal:A
message was sent to tcp/127.0.0.1:19114.
Aug 26, 2000 2:10:34
PM:Freenet.client.events.StateReachedEvent:1023591519:Normal:State "DONE"
reached.
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Finished
with message
Aug 26, 2000 2:10:34 PM:Freenet.ConnectionHandler:1213383768:Debug:Finished
with connection - closing
Inserted Key : freenet:KSK@/degory/test106
Aug 26, 2000 2:10:35 PM:Freenet.Conduit:455787615:Debug:1- Conduit finished
moved 38 bytes.
inserts with HTL>1 now terminate with NullPointerException after long pause.
Trace:
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'tickerTime'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'connectTimeout'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'connectionTimeout'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'handshakeTimeout'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'handshakeLife'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'hopTimeExpected'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'hopTimeDeviation'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'maximumConnectionThreads'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'bufferSize'
Aug 26, 2000 2:17:19 PM:Freenet.Params:1063699894:Debug:No value specified
for parameter 'keyTypes'
Aug 26, 2000 2:17:19 PM:Freenet.transport.tcpConnection:-918633039:Debug:not
throttling local connection
Aug 26, 2000 2:17:19
PM:Freenet.client.ClientCore:-1010907727:Debug:Connection between:
tcp/127.0.0.1:1417 and tcp/127.0.0.1:19114
Aug 26, 2000 2:17:19 PM:Freenet.ConnectionHandler:-1037122127:Debug:New
connectionhandler with tcp/127.0.0.1:19114
Aug 26, 2000 2:17:19 PM:Freenet.ConnectionHandler:-1037122127:Debug:Sending
a message
Aug 26, 2000 2:17:19
PM:Freenet.ConnectionHandler:-1037122127:Normal:810d7bf47663043a -
HandshakeRequest -> tcp/127.0.0.1:19114
Aug 26, 2000 2:17:20
PM:Freenet.presentation.FNPRawMessage:-299711055:Debug:Reading message
Aug 26, 2000 2:17:20
PM:Freenet.ConnectionHandler:-1037122127:Minor:Rawmessage:
HandshakeReply
{Source=tcp/127.0.0.1:19114,KeepAlive=true,DataLength=0,{Depth=1,Build=146,R
evision=1.51,UniqueID=810d7bf47663043a,Version=1.29,HopsToLive=1,}}
Aug 26, 2000 2:17:20
PM:Freenet.ConnectionHandler:-1037122127:Normal:810d7bf47663043a -
HandshakeReply <- tcp/127.0.0.1:19114
Aug 26, 2000 2:17:20 PM:Freenet.ConnectionHandler:-1037122127:Debug:Message:
Src:null htl:1 depth:1 id:810d7bf47663043a
type:Freenet.message.HandshakeReply
Aug 26, 2000 2:17:20 PM:Freenet.ConnectionHandler:-1037122127:Debug:Got on
tcp/127.0.0.1:1418 from tcp/127.0.0.1:19114
Handled: Src:tcp/127.0.0.1:19114 htl:1 depth:1 id:810d7bf47663043a
type:Freenet.message.HandshakeReply
Aug 26, 2000 2:17:20
PM:Freenet.message.HandshakeReply:-450443855:Debug:Setting handshake from
tcp/127.0.0.1:19114
Aug 26, 2000 2:17:20
PM:Freenet.StandardHandshakeHandler:-745093711:Debug:Done waiting for
handshake, returning true
Aug 26, 2000 2:17:20
PM:Freenet.client.events.StateReachedEvent:381339062:Normal:State "PREPARED"
reached.
Aug 26, 2000 2:17:20 PM:Freenet.ConnectionHandler:-1037122127:Debug:Sending
a message
Aug 26, 2000 2:17:20
PM:Freenet.ConnectionHandler:-1037122127:Normal:6dbfe4d1306e3def -
InsertRequest -> tcp/127.0.0.1:19114
Aug 26, 2000 2:17:20 PM:Freenet.client.events.SendEvent:68339126:Normal:A
message was sent to tcp/127.0.0.1:19114.
Aug 26, 2000 2:17:20
PM:Freenet.client.events.StateReachedEvent:7259574:Normal:State "REQUESTING"
reached.
Aug 26, 2000 2:17:20 PM:Freenet.ConnectionHandler:-1037122127:Debug:Finished
with message
Aug 26, 2000 2:18:39
PM:Freenet.presentation.FNPRawMessage:1364379062:Debug:Reading message
Aug 26, 2000 2:18:39
PM:Freenet.ConnectionHandler:-1037122127:Minor:Rawmessage:
QueryRestarted
{Source=tcp/127.0.0.1:19114,KeepAlive=true,DataLength=0,{Depth=0,UniqueID=6d
bfe4d1306e3def,HopsToLive=5,}}
Aug 26, 2000 2:18:39
PM:Freenet.ConnectionHandler:-1037122127:Normal:6dbfe4d1306e3def -
QueryRestarted <- tcp/127.0.0.1:19114
Aug 26, 2000 2:18:39 PM:Freenet.ConnectionHandler:-1037122127:Debug:Message:
Src:null htl:5 depth:0 id:6dbfe4d1306e3def
type:Freenet.message.QueryRestarted
Aug 26, 2000 2:18:39 PM:Freenet.ConnectionHandler:-1037122127:Debug:Got on
tcp/127.0.0.1:1418 from tcp/127.0.0.1:19114
Handled: Src:tcp/127.0.0.1:19114 htl:5 depth:0 id:6dbfe4d1306e3def
type:Freenet.message.QueryRestarted
Aug 26, 2000 2:18:39
PM:Freenet.client.events.ReceiveEvent:1666893238:Normal:A message was
received from tcp/127.0.0.1:19114.
Aug 26, 2000 2:18:39 PM:Freenet.ConnectionHandler:-1037122127:Debug:Finished
with message
Aug 26, 2000 2:18:39
PM:Freenet.client.events.RestartedEvent:-1636383306:Normal:The query was
restarted somewhere on Freenet after a node failed to reply, waiting another
94 seconds before I give up
Aug 26, 2000 2:18:41
PM:Freenet.presentation.FNPRawMessage:-1613838922:Debug:Reading message
Aug 26, 2000 2:18:41
PM:Freenet.ConnectionHandler:-1037122127:Minor:Rawmessage:
RequestFailed
{Source=tcp/127.0.0.1:19114,KeepAlive=false,DataLength=0,{Depth=1,UniqueID=6
dbfe4d1306e3def,HopsToLive=3,}}
Aug 26, 2000 2:18:41
PM:Freenet.ConnectionHandler:-1037122127:Normal:6dbfe4d1306e3def -
RequestFailed <- tcp/127.0.0.1:19114
Aug 26, 2000 2:18:41 PM:Freenet.ConnectionHandler:-1037122127:Debug:Message:
Src:null htl:3 depth:1 id:6dbfe4d1306e3def
type:Freenet.message.RequestFailed
Aug 26, 2000 2:18:41 PM:Freenet.ConnectionHandler:-1037122127:Debug:Got on
tcp/127.0.0.1:1418 from tcp/127.0.0.1:19114
Handled: Src:tcp/127.0.0.1:19114 htl:3 depth:1 id:6dbfe4d1306e3def
type:Freenet.message.RequestFailed
Aug 26, 2000 2:18:41
PM:Freenet.client.events.ReceiveEvent:-1661811274:Normal:A message was
received from tcp/127.0.0.1:19114.
Aug 26, 2000 2:18:41 PM:Freenet.ConnectionHandler:-1037122127:Debug:Finished
with message
Aug 26, 2000 2:18:41 PM:Freenet.ConnectionHandler:-1037122127:Debug:Finished
with connection - closing
Aug 26, 2000 2:18:41
PM:Freenet.client.events.ExceptionEvent:-1972189770:Normal:A fatal exception
occured while processing: java.lang.NullPointerException
Aug 26, 2000 2:18:41
PM:Freenet.client.events.StateReachedEvent:-2089892426:Normal:State "FAILED"
reached.
java.lang.NullPointerException
at Freenet.client.Client$BInsertInstance.doit(Client.java, Compiled
Code)
at Freenet.client.Client$BInstance.prepare(Client.java, Compiled Code)
at Freenet.client.Client$BInsertInstance.prepare(Client.java, Compiled
Code)
at Freenet.client.Client$BInstance.run(Client.java, Compiled Code)
at java.lang.Thread.run(Thread.java, Compiled Code)
-----Original Message-----
From: [email protected]
[mailto:freenet-dev-admin at lists.sourceforge.net]On Behalf Of Oskar
Sandberg
Sent: 26 August 2000 14:28
To: freenet-dev at lists.sourceforge.net
Subject: Re: [Freenet-dev] Dynamic DNS/Connection timeout
While the symptoms seem concerning, I don't think your analysis is
correct. The DNS lookup doesn't occur while connecting, but when the
address object is constructed from the reference, and that time is not
included in the connect timeout.
You could try setting up the connect timeout anyways, if a domain name
lookup takes you 5 seconds you must be a on a very shabby connections (it
took me about 2, and I'm on a modem... in Indonesia).
If that doesn't help I want to see logs (logging=debugging ,
verbosity=5) of what happens on your node when you make a request with HTL
> 1.
On Sat, Aug 26, 2000 at 02:05:40PM +0100, degs wrote:
> Hi,
>
> I think that the default connection timeouts (2000ms) in .freenetrc are
too
> short for dynamic DNS. It takes a good 5 seconds to lookup
> ceequdee.no-ip.com. I think that this is affecting other nodes' ability to
> connect to me.
>
> The symptoms are:
>
> * my node is definitely running and visible from the net: I can telnet
from
> work in to ceequdee.no-ip.com:19114 and get what looks like FP out
> * netstat shows connections to other nodes in various TCP states (I can
send
> this output if it is useful) but practically no traffic goes over the
line.
> * I can insert data locally and only that data which I have inserted
locally
> ends up in my datastore.
>
> The upshot is I don't think any other node is able to contact my node.
>
> Degs
>
> PS: apologies if this message is an (approximate) duplicate, Real JukeBox
> turned my machine inside-out mid send in the previous attempt...
>
> _______________________________________________
> Freenet-dev mailing list
> Freenet-dev at lists.sourceforge.net
> http://lists.sourceforge.net/mailman/listinfo/freenet-dev
>
--
\oskar
_______________________________________________
Freenet-dev mailing list
Freenet-dev at lists.sourceforge.net
http://lists.sourceforge.net/mailman/listinfo/freenet-dev
_______________________________________________
Freenet-dev mailing list
Freenet-dev at lists.sourceforge.net
http://lists.sourceforge.net/mailman/listinfo/freenet-dev
