Re: Pinging?

[tainaron@xxxxxxxxxxxxxxxx]

Hi Nick,

tainaron wrote:
> I've downgraded to SQLite 2.8.16-r4 and pysqlite 0.5.1 and now
> it seems to work. Downgrading pysqlite alone did not help, and
> pysqlite 2.3 needs SQLite >=3.1, at least with Gentoo Linux.

I've let the pinger run for a while, but I noticed that almost no
pings were queued: when the scheduled time was reached, the pings
seemed to get just re-scheduled one day later, but they were not
queued to be sent.

In the pinger code I noticed that the check whether a ping has
already been scheduled often returns "None" at my node, even if
a ping has been scheduled before, according to the log:

===== Beginn =====
Pinger.py, line 1410ff:

   when = self.nextPingTime.get((id1,id2))
   if when is None:
       # No ping scheduled; server must be new to directory.
       self._schedulePing((id1,id2),now)

===== Ende =====

At _schedulePing() I noticed the line

===== Beginn =====
Pinger.py, line 1262ff:

   path = tuple([ p.lower() for p in path ])

   [...]

   self.nextPingTime[path] = t

===== Ende =====

so I wondered, if the path variable is modified, then the stored
and checked paths maybe differ? Giving it a try, I commented line
1262 and now pings are being queued regularly.

However, on the downside, now I get a lot of messages "Server is
not currently valid":

===== Beginn =====

Sep 27 22:22:43 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:22:43 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path frell,parakalo [Hl/xC8JHKQ9U2wsz1eq8mRQnAe4=]
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that 4ff433614765c35705eddb84adad1ca4b1e1d69f,53ee85e4f68d8df389a78d53221bf5baba458400 was dull
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for 4ff433614765c35705eddb84adad1ca4b1e1d69f,53ee85e4f68d8df389a78d53221bf5baba458400 at 2007-09-28 10:24:05
Sep 27 22:22:43 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:22:43 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path Rivendell,pboxxl [iHZu+K587or/YjS+rLSHWgWzl0Y=]
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that ddabdf1feac8a97e811078688da2430be2cbbf23,23630bf83dbfd36098ed8391714751ea3d20573d was dull
Sep 27 22:22:43 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for ddabdf1feac8a97e811078688da2430be2cbbf23,23630bf83dbfd36098ed8391714751ea3d20573d at 2007-09-28 11:25:11
Sep 27 22:23:44 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path yog,tainaron [9nSZ9osvd9iKMej5PG31V8YN/Mw=]
Sep 27 22:23:44 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that edc14fa168a2f9a82492c2d9c2e657ed2695a9e7,845ee1e0076ad9cf6a51a1755e30899d3659f401 was dull
Sep 27 22:23:44 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for edc14fa168a2f9a82492c2d9c2e657ed2695a9e7,845ee1e0076ad9cf6a51a1755e30899d3659f401 at 2007-09-28 01:03:42
Sep 27 22:24:45 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path grove [G/ZLegq8Y16qAwJLZ3W3ytHMXMs=]
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Scheduling 1-hop ping for e8faab7443b485549d31c932936be999088e160a at 2007-09-27 23:24:42
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path deuxpi [NQ7lr8jaTkQWyRct+OLbi7vZCec=]
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Scheduling 1-hop ping for 56cabf3e2310f7200938d34ec5aa30918115219f at 2007-09-27 23:24:23
Sep 27 22:24:45 themis mixminiond[22288]: [ERROR] Server is not currently valid
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path xbox,pboxlevel3 [GGuxdt3ODNsz9YvZ0ZUKgnGrglQ=]
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that e8faab7443b485549d31c932936be999088e160a,3479f0ac57873c5cde52d49ea4224f64c8acfd4a was dull
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for e8faab7443b485549d31c932936be999088e160a,3479f0ac57873c5cde52d49ea4224f64c8acfd4a at 2007-09-28 16:02:55
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path deuxpi,lakshmi [QK/vMt2b4Wv677dUIxD2yGklKNs=]
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that 56cabf3e2310f7200938d34ec5aa30918115219f,fd3e24c5c7bdb030dea346ceb8b0062ac4c41877 was dull
Sep 27 22:24:45 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for 56cabf3e2310f7200938d34ec5aa30918115219f,fd3e24c5c7bdb030dea346ceb8b0062ac4c41877 at 2007-09-28 14:35:52
Sep 27 22:25:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path tainaron [riRIPrjyNHomO6HYWASNluG7Ycc=]
Sep 27 22:25:45 themis mixminiond[22288]: [DEBUG] Scheduling 1-hop ping for 845ee1e0076ad9cf6a51a1755e30899d3659f401 at 2007-09-27 23:25:28
Sep 27 22:25:45 themis mixminiond[22288]: [DEBUG] Pinger queueing ping along path nowwhat2,frell [rW07iRRnw+juJIildlMuzxANuqw=]
Sep 27 22:25:45 themis mixminiond[22288]: [DEBUG] While scheduling, I decided that 264efcc43d0fb6d4337a0d286650afb78d56d9bb,249469c54d79b9782221e2803caaeb9eb19fa247 was dull
Sep 27 22:25:45 themis mixminiond[22288]: [DEBUG] Scheduling 2-hop ping for 264efcc43d0fb6d4337a0d286650afb78d56d9bb,249469c54d79b9782221e2803caaeb9eb19fa247 at 2007-09-28 20:53:17

===== Ende =====

(Without touching line 1262, there'd only be "Scheduling...", but
no "Pinger queueing..." lines.)

Somehow, the error seems to arise at

===== Beginn =====
Pinger.py, line 1196f:

    p1 = self.directory.getPath(path1)
    p2 = self.directory.getPath(path2)
 
===== Ende =====

but I'm puzzled, why the server should not be valid resp. if the
queueing succeeds... I can't yet tell, if the pings are actually
sent, but could you please have a look into the issue? Thanks.

Ciao

Tobias
-- 
mbox:admin@tainaron