[PROXYPER] Bug? Dupe source

Petr Novotny Petr.Novotny at antek.cz
Tue Mar 2 13:59:52 EST 1999


Hi,

reading through the logs of perproxy (build 304 on Linux) and client 
(build 326 NT service) I have found the following oddity:

from the proxy:
03/02/99 12:17:50,rc564 r=599/600, d=1/1, 0.0 Mkeys/sec, tot=325
03/02/99 12:17:50,desII r=0/20, d=0/1, 0.0 Mkeys/sec, tot=0
03/02/99 12:18:14,Accepted connection from address 192.168.1.4
03/02/99 12:18:15,192.168.1.4: Client requesting communication
03/02/99 12:18:16,192.168.1.4: assigned rc564 84a71742:60000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 84a71742:70000000, iter 1
03/02/99 12:18:16,Using new randomprefix 0x89, replacing 0x85
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659883:e0000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659883:f0000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:00000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:10000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:20000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:30000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:40000000, iter 1
03/02/99 12:18:16,192.168.1.4: assigned rc564 88659884:50000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:70000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:80000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:90000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:a0000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:b0000000, iter 1
03/02/99 12:18:17,192.168.1.4: completed rc564 88cf51f9:c0000000, iter 1
03/02/99 12:18:17,Creating outgoing server connection.
03/02/99 12:20:47,Error locating host us.v27.distributed.net
03/02/99 12:20:47,Server 0.0.0.0 changed from state 0 to 9
03/02/99 12:20:47,server: Closing server connection.
03/02/99 12:20:47,192.168.1.4: completed rc564 88cf51f9:d0000000, iter 1
03/02/99 12:20:47,192.168.1.4: completed rc564 88cf51f9:e0000000, iter 1
03/02/99 12:20:47,rc564 r=589/600, d=9/1, 0.6 Mkeys/sec, tot=333
03/02/99 12:20:47,desII r=0/20, d=0/1, 0.0 Mkeys/sec, tot=0
03/02/99 12:20:47,Client 192.168.1.4 is no longer connected (reading).
03/02/99 12:20:47,192.168.1.4: Closing client connection.
03/02/99 12:21:18,rc564 r=589/600, d=9/1, 0.6 Mkeys/sec, tot=333
03/02/99 12:21:18,desII r=0/20, d=0/1, 0.0 Mkeys/sec, tot=0
[snip]
03/02/99 12:41:53,rc564 r=589/600, d=9/1, 0.6 Mkeys/sec, tot=333
03/02/99 12:41:53,desII r=0/20, d=0/1, 0.0 Mkeys/sec, tot=0
03/02/99 12:42:17,Accepted connection from address 192.168.1.4
03/02/99 12:42:17,192.168.1.4: Client requesting communication
03/02/99 12:42:17,Using new randomprefix 0x87, replacing 0x89
03/02/99 12:42:18,192.168.1.4: assigned rc564 86722f82:30000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:60000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:e0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:d0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:c0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:b0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:a0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:90000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:80000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:70000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 88cf51f9:f0000000, iter 1
03/02/99 12:42:18,192.168.1.4: completed rc564 8816adbd:20000000, iter 1
03/02/99 12:42:18,Client 192.168.1.4 is no longer connected (reading).
03/02/99 12:42:18,192.168.1.4: Closing client connection.
03/02/99 12:42:24,rc564 r=588/600, d=20/1, 1.3 Mkeys/sec, tot=344
03/02/99 12:42:24,desII r=0/20, d=0/1, 0.0 Mkeys/sec, tot=0


from the client (it's clock is one hour in advance):
[Mar 02 13:26:23 UTC] Completed one RC5 block 88CF51F9:70000000 (1*2^28 keys)
                      0.00:23:54.19 - [187,168.42 keys/sec]
[Mar 02 13:26:23 UTC] Summary: 9 RC5 blocks 0.04:25:55.53 - [151.16 kkeys/s]
[Mar 02 13:26:25 UTC] The perproxy says: "Linux alive and kicking"
[Mar 02 13:26:25 UTC] Retrieved 10 blocks (10*2^28 keys) from server.
[Mar 02 13:27:26 UTC] Flush::Bad packet acknowledgement.
[Mar 02 13:27:26 UTC] 10 RC5 blocks (10*2^28 keys) remain in
                      d:\rc5des\buff-in.rc5
[Mar 02 13:27:26 UTC] 10 RC5 blocks (10*2^28 keys) are in
                      d:\rc5des\buff-out.rc5
[Mar 02 13:50:26 UTC] Completed one RC5 block 88CF51F9:60000000 (1*2^28 keys)
                      0.00:24:02.67 - [186,067.99 keys/sec]
[Mar 02 13:50:26 UTC] Summary: 10 RC5 blocks 0.04:49:58.20 - [154.06 kkeys/s]
[Mar 02 13:50:27 UTC] The perproxy says: "Linux alive and kicking"
[Mar 02 13:50:27 UTC] Retrieved 1 block (1*2^28 keys) from server.
[Mar 02 13:50:27 UTC] Sent 11 blocks (11*2^28 keys) to server.
[Mar 02 13:50:27 UTC] 10 RC5 blocks (10*2^28 keys) remain in
                      d:\rc5des\buff-in.rc5
[Mar 02 13:50:27 UTC] 0 RC5 blocks (0*2^28 keys) are in
                      d:\rc5des\buff-out.rc5


If you read carefully the log of the proxy, you'll notice that 8 
blocks from the clients are counted twice (and in fact submitted 
twice):
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:70000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:80000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:90000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:a0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:b0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:c0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:d0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:e0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:60000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:e0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:d0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:c0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:b0000000, iter 1
03/02/99 12:43:42,server: Queueing rc564 block 88cf51f9:a0000000, iter 1
03/02/99 12:43:44,server: Queueing rc564 block 88cf51f9:90000000, iter 1
03/02/99 12:43:44,server: Queueing rc564 block 88cf51f9:80000000, iter 1
03/02/99 12:43:44,server: Queueing rc564 block 88cf51f9:70000000, iter 1


(From the ini settings, maxkeysdone=1 may be interesting; plus the 
fact that the Internet connection was down till 12:43)


So, coders, if you think the master is flooded with dupes, this is 
one of the reasons...
--
Petr Novotny, ANTEK CS
Petr.Novotny at antek.cz
http://www.antek.cz
-- Don't you know there ain't no devil there's just God when he's drunk.
                                                             [Tom Waits]
--
To unsubscribe, send 'unsubscribe proxyper' to majordomo at lists.distributed.net



More information about the proxyper mailing list