rsync 3.0.9 hangs on large file

[Greg Bell]

Hi Folks,

Similar to an earlier thread, but slightly more ordinary.  My old 
rsync backup script, which worked fine under Ubuntu 12.04, hangs on 
Ubuntu 12.10 (rsync 3.0.9) and a 250 MB file.  Command line as follows:

rsync      --itemize-changes --human-readable  --progress --delete \
     --delete-excluded --compress --bwlimit=18 --recursive --archive \
     --partial --partial-dir=~/partial     --exclude=.cache/
     --exclude=*/tmp/
     --exclude=*/cache/
     ...plus a few dozen more excludes...
      /home/gbell2     gbell at moms:backups/gbell_backups_mnt

Destination dir is a mounted truecrypt volume.

STDOUT is as follows:
match at 215148020 last_match=215148020 j=14631 len=14648 n=0
match at 215162668 last_match=215162668 j=14632 len=14648 n=0
match at 215177316 last_match=215177316 j=14633 len=14648 n=0
match at 215244029 last_match=215224732 j=4387 len=14648 n=19297
      215.26M  92%   68.04MB/s    0:00:00
match at 215272589 last_match=215258677 j=14524 len=14648 n=13912
match at 215287237 last_match=215287237 j=14525 len=14648 n=0
match at 215301885 last_match=215301885 j=14526 len=14648 n=0
match at 215374610 last_match=215349301 j=14571 len=14648 n=25309
      215.39M  92%   47.21MB/s    0:00:00
match at 215389258 last_match=215389258 j=14572 len=14648 n=0
match at 215403906 last_match=215403906 j=14573 len=14648 n=0
match at 215418554 last_match=215418554 j=14574 len=14648 n=0
match at 215490292 last_match=215465970 j=4387 len=14648 n=24322
      215.80M  92%   23.05kB/s    0:12:42

(hangs here)


And tail of strace on remote machine is as follows:

22291 read(0, "2aiprXqxTXlj1hiaR7GUhQD6V/w9slSx"..., 262680) = 262680
22291 read(0, "kMcpjl44yeORn+df8/Rw/5z\nnRO86PId"..., 262680) = 262680
22291 read(0, "05ptYv0tLlbiW4KyIqIS74PzFEjJBAMm"..., 262680) = 262680
22291 read(0, "gS8y/mGdnDhA8RG3bxuTg4qVJe9Hls10"..., 262680) = 262680
22291 read(0, "b\niq0ovsrfK4RhaHOlezPV7T/gmzK6L9"..., 262680) = 262680
22291 read(0, "TjpXCXtjJbFkZcSKTv67epH69c10cys4"..., 262680) = 262680
22291 read(0, "vULayOfXn4tfO1ruNetLjza+nwsU5byJ"..., 262680) = 262680
22291 read(0, "Mcl3KlkHKRsi\nYkAiO4hNu4gADtjpnjr"..., 262680) = 262680
22291 select(4, [3], [1], [1], {60, 0}) = 2 (in [3], out [1], left 
{59, 999990})
22291 write(1, "\374\17\0\7", 4)        = 4
22291 time(NULL)                        = 1357170281
22291 gettimeofday({1357170281, 62644}, NULL) = 0
22291 select(4, [3], [1], [1], {60, 0}) = 2 (in [3], out [1], left 
{59, 999994})
22291 select(4, [3], [], NULL, {60, 0}) = 1 (in [3], left {59, 999994})
22291 read(3, "L\0\0\nrsync error: error in file I"..., 8184) = 169
22291 write(1, 
"\363g\277\317W\345z\324T\330\263\f\345\356\332.k_\216\256\245\345\273\336\37\317$O\315\310\244\6"..., 
2304) = 2304
22291 time(NULL)                        = 1357170281
22291 gettimeofday({1357170281, 63724}, NULL) = 0
22291 select(0, NULL, NULL, NULL, {0, 123897}) = 0 (Timeout)
22291 gettimeofday({1357170281, 188242}, NULL) = 0
22291 select(4, [3], [1], [1], {60, 0}) = 2 (in [3], out [1], left 
{59, 999990})
22291 write(1, 
"\261|e\f%\274\302#\274\207Q\0226\316\250\253C\216\260\3\263}\352\31\202S:\253'\351)G"..., 
1788) = 1788
22291 time(NULL)                        = 1357170281
22291 gettimeofday({1357170281, 189457}, NULL) = 0
22291 select(4, [3], [1], [1], {60, 0}) = 2 (in [3], out [1], left 
{59, 999992})
22291 select(4, [3], [], NULL, {60, 0}) = 1 (in [3], left {59, 999996})
22291 read(3, "", 8184)                 = 0
22291 rt_sigaction(SIGUSR1, {SIG_IGN, [], 0}, NULL, 8) = 0
22291 rt_sigaction(SIGUSR2, {SIG_IGN, [], 0}, NULL, 8) = 0
22291 kill(22292, SIGUSR1)              = -1 ESRCH (No such process)
22291 gettimeofday({1357170281, 190979}, NULL) = 0
22291 select(0, NULL, NULL, NULL, {0, 100000}) = 0 (Timeout)
22291 gettimeofday({1357170281, 291655}, NULL) = 0
22291 exit_group(12)                    = ?


Anything jump out at anybody?


Many thanks,
Greg Bell