needinfo canceled: [Bug 649069] rsync transfer reports incorrect transfer speed

[bugzilla at redhat.com]

Product: Fedora
Version: 12
Component: rsync

Mike C <mike.cloaked at gmail.com> has canceled Bug Zapper
<fedora-triage-list at redhat.com>'s request for needinfo:
Bug 649069: rsync transfer reports incorrect transfer speed
https://bugzilla.redhat.com/show_bug.cgi?id=649069

------- Additional Comments from Mike C <mike.cloaked at gmail.com>

$ rsync -avvvv --progress path-to-file fpath-to-remote/
cmd=<NULL> machine=fiz user=<NULL> path=~/mnt/www/mike/albums/2010/fall2010/
cmd[0]=ssh cmd[1]=fiz cmd[2]=rsync cmd[3]=--server cmd[4]=-vvvvlogDtpre.iLs
cmd[5]=. cmd[6]=~/mnt/www/mike/albums/2010/fall2010/ 
opening connection using: ssh fiz rsync --server -vvvvlogDtpre.iLs .
"~/mnt/www/mike/albums/2010/fall2010/" 
note: iconv_open("UTF-8", "UTF-8") succeeded.

(Client) Protocol versions: remote=30, negotiated=30
sending incremental file list
[sender] make_file(IMG_20101101_085101.jpg,*,0)
[sender] flist start=1, used=1, low=0, high=0
[sender] i=1 /home/mike/Pictures/university/fall2010 IMG_20101101_085101.jpg
mode=0100664 len=1253272 uid=500 gid=500 flags=5
send_file_list done
file list sent
send_files starting
server_recv(2) starting pid=3822
(Server) Protocol versions: remote=30, negotiated=30
received 1 names
[Receiver] flist start=1, used=1, low=0, high=0
[Receiver] i=1 1 IMG_20101101_085101.jpg mode=0100664 len=1253272 gid=513
flags=0
recv_file_list done
get_local_name count=1 /home/mike/mnt/www/mike/albums/2010/fall2010/
generator starting pid=3822
delta-transmission enabled
recv_generator(IMG_20101101_085101.jpg,1)
send_files(1, /home/mike/Pictures/university/fall2010/IMG_20101101_085101.jpg)
count=0 n=0 rem=0
send_files mapped
/home/mike/Pictures/university/fall2010/IMG_20101101_085101.jpg of size 1253272

calling match_sums
/home/mike/Pictures/university/fall2010/IMG_20101101_085101.jpg
IMG_20101101_085101.jpg
       32768   2%    0.00kB/s	 0:00:00
sending file_sum
false_alarms=0 hash_hits=0 matches=0
     1253272 100%   58.20MB/s	 0:00:00 (xfer#1, to-check=0/1)
sender finished /home/mike/Pictures/university/fall2010/IMG_20101101_085101.jpg

recv_files(1) starting
generate_files phase=1
recv_files(IMG_20101101_085101.jpg)
data recv 32768 at 0
data recv 32768 at 32768
data recv 32768 at 65536
data recv 32768 at 98304
data recv 32768 at 131072
data recv 32768 at 163840
data recv 32768 at 196608
data recv 32768 at 229376
data recv 32768 at 262144
data recv 32768 at 294912
data recv 32768 at 327680
data recv 32768 at 360448
data recv 32768 at 393216
data recv 32768 at 425984
data recv 32768 at 458752
data recv 32768 at 491520
data recv 32768 at 524288
data recv 32768 at 557056
data recv 32768 at 589824
data recv 32768 at 622592
data recv 32768 at 655360
data recv 32768 at 688128
data recv 32768 at 720896
data recv 32768 at 753664
data recv 32768 at 786432
data recv 32768 at 819200
data recv 32768 at 851968
data recv 32768 at 884736
data recv 32768 at 917504
data recv 32768 at 950272
data recv 32768 at 983040
data recv 32768 at 1015808
data recv 32768 at 1048576
data recv 32768 at 1081344
data recv 32768 at 1114112
data recv 32768 at 1146880
data recv 32768 at 1179648
data recv 32768 at 1212416
data recv 8088 at 1245184
got file_sum
set modtime of .IMG_20101101_085101.jpg.sP9mq9 to (1288623180) Mon Nov	1
10:53:00 2010
renaming .IMG_20101101_085101.jpg.sP9mq9 to IMG_20101101_085101.jpg
send_files phase=1
recv_files phase=1
generate_files phase=2
send_files phase=2
send files finished
total: matches=0  hash_hits=0  false_alarms=0 data=1253272
recv_files phase=2
generate_files phase=3
recv_files finished
generate_files finished
client_run waiting on 13991

sent 1253528 bytes  received 31 bytes  32559.97 bytes/sec
total size is 1253272  speedup is 1.00
_exit_cleanup(code=0, file=main.c, line=1039): entered
_exit_cleanup(code=0, file=main.c, line=1039): about to call exit(0)

Note that 4 lines up from here we see 32559.97 bytes/sec which I can believe,
but further up we see:

     1253272 100%   58.20MB/s	 0:00:00 (xfer#1, to-check=0/1)

which is wrong for the speed.