thr3ads.net - rsync - rsync on cygwin is hanging [Jul 2003]

If this information is useful, please help other people find it:
Share via:

Jeff Hedlund

2003-Jul-20 06:06 UTC

rsync on cygwin is hanging

I am trying to backup a Windows machine to a Linux machine using rsync 
(over ssh).

I have been able to successfully sync a few files at a time, but when I 
try to backup an entire tree the process hangs on both machines.

I believe this is a different problem than one posted on here before, 
but I did try this patch with no success: 
http://www.mail-archive.com/rsync@lists.samba.org/msg07525.html

When I try to rsync a tree, it will usually create a partial directory 
structure and download a few files and then hang.

Linux:  RH 7.3, rsync 2.5.6, OpenSSH_3.1p1
Cygwin: 5.0, rsync 2.5.6, OpenSSH_3.6.1p1

Let me know if more information is needed (and I apologize if too much 
is sent) :).

I am running the following on the linux machine:
rsync -vvrltDH --numeric-ids -pgo --stats --exclude-from=exclude.list 
--rsh="ssh" user@host:c:/WINNT/ tree

Here is the tail end of the output from that command where it hangs:

--- rsync.out ---
system32/wbem/mof/
system32/wbem/mof/bad/
system32/wbem/mof/good/
system32/wins/
twain_32/
twain_32/fjscan/
twain_32/fjscan/fcpa/
twain_32/logiscan/
twain_32/miitwain/
wudnld20.tmp/
--- eof: rsync.out ---

Here is the strace on the linux machine where it hangs:

--- strace.out (on linux machine) ---
lstat64("twunk_16.exe", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
lstat64("twunk_32.exe", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
lstat64("upwizun.exe", 0xbffffa20)      = -1 ENOENT (No such file or 
directory)
lstat64("vb.ini", 0xbffffa20)           = -1 ENOENT (No such file or 
directory)
lstat64("vbaddin.ini", 0xbffffa20)      = -1 ENOENT (No such file or 
directory)
lstat64("vmmreg32.dll", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
lstat64("welcome.exe", 0xbffffa20)      = -1 ENOENT (No such file or 
directory)
lstat64("welcome.ini", 0xbffffa20)      = -1 ENOENT (No such file or 
directory)
lstat64("win.ini", 0xbffffa20)          = -1 ENOENT (No such file or 
directory)
lstat64("winhelp.exe", 0xbffffa20)      = -1 ENOENT (No such file or 
directory)
lstat64("winhlp32.exe", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
lstat64("winnt.bmp", 0xbffffa20)        = -1 ENOENT (No such file or 
directory)
lstat64("winnt256.bmp", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
lstat64("winrep.exe", 0xbffffa20)       = -1 ENOENT (No such file or 
directory)
lstat64("wudnld20.tmp", 0xbffffa20)     = -1 ENOENT (No such file or 
directory)
mkdir("wudnld20.tmp", 040700)       = 0
lstat64("wudnld20.tmp", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
time(NULL)                              = 1058644307
utime("wudnld20.tmp", [2003/07/19-15:51:47, 2002/01/25-01:17:08]) = 0
lchown32(0x8074940, 0x220, 0x201)       = 0
write(1, "wudnld20.tmp/\n", 14)         = 14
select(8, [7], [4], NULL, {60, 0})      = 1 (out [4], left {60, 0})
write(4, "\274\2\0\0\0\0\0\0\202\36\0\0\0\0\0\0\274\2\0\0\0\0\0\0"...,
3916)
    = 3916
select(8, [3 7], NULL, NULL, {60, 0})       = 0 (Timeout)
select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
select(8, [3 7], NULL, NULL, {60, 0}
--- eof: strace.out (on linux machine) ---




Here is the output of the child rsync process on the linux box:

--- strace.out.3341 (child rsync on linux machine) ---
close(3)                                = 0
close(7)                                = 0
close(4)                                = 0
fcntl64(8, F_GETFL)                     = 0x802 (flags O_RDWR|O_NONBLOCK)
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\1\0\0\0", 4)                  = 4
open("$NtServicePackUninstall$/2jl3vz7j.zip", O_RDONLY|O_LARGEFILE) =
-1
ENOENT (No such file or directory)
gettimeofday({1058644307, 137041}, NULL) = 0
getpid()                                = 3341
open("$NtServicePackUninstall$/.2jl3vz7j.zip.dt2mgM", 
O_RDWR|O_CREAT|O_EXCL|O_LARGEFILE, 0600) = 3
fchmod(3, 0700)                         = 0
write(1, "$NtServicePackUninstall$/2jl3vz7"..., 38) = 38
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\274\2\0\0", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\315*\0\0", 4)                 = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5,
"PK\3\4\n\0\0\0\0\0\363i\374\":7\213U\367\0\0\0\367\0\0"...,
4072) = 4072
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\4\0\0\0\0\0\6\0\0\0\10\0\0\0\1\0\0\0\0\4\1\0\37"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\375\n\0\7", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "/Throwable\7\0/\1\0\27Unable to loadLi"..., 2793) = 2793
gettimeofday({1058644307, 140397}, NULL) = 0
write(3,
"PK\3\4\n\0\0\0\0\0\363i\374\":7\213U\367\0\0\0\367\0\0"...,
10957) = 10957
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\227\302\240O\277,\4\34!\211\226hx3\242R", 16) = 16
close(3)                                = 0
rename("$NtServicePackUninstall$/.2jl3vz7j.zip.dt2mgM", 
"$NtServicePackUninstall$/2jl3vz7j.zip") = 0
lstat64("$NtServicePackUninstall$/2jl3vz7j.zip",
{st_mode=S_IFREG|0700,
st_size=10957, ...}) = 0
time(NULL)                              = 1058644307
utime("$NtServicePackUninstall$/2jl3vz7j.zip", [2003/07/19-15:51:47, 
1999/12/06-08:00:00]) = 0
lchown32(0x8078940, 0x220, 0x201)       = 0
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 970000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\2\0\0\0", 4)                  = 4
open("$NtServicePackUninstall$/3vxnfhbx.zip", O_RDONLY|O_LARGEFILE) =
-1
ENOENT (No such file or directory)
getpid()                                = 3341
open("$NtServicePackUninstall$/.3vxnfhbx.zip.P6tDge", 
O_RDWR|O_CREAT|O_EXCL|O_LARGEFILE, 0600) = 3
fchmod(3, 0700)                         = 0
write(1, "$NtServicePackUninstall$/3vxnfhb"..., 38) = 38
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\274\2\0\0", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\0\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\200\0\0", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "PK\3\4\n\0\0\0\0\0\\\201e%M\37A\0\302\6\0\0\302\6\0\0\37"...,
4072) = 4072
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "/ms/xml/om/Element;Lcom/ms/xml/o"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 950000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "ment\1\0\7setRoot\1\0\5clear\1\0\tasyncLo"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "ns\1\0\36com/ms/xml/dso/XMLParserThr"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 940000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "/lang/Object;)V\1\0\20getEstimatedRo"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "ml/om/ElementFactory;Lcom/ms/xml"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 850000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "r*\264\0*\266\0v*\264\0r+**\264\0z**\264\0}*\264\0\200"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\f\22*\270\0\26Y\263\0(S:\f\4\275\0\33Y\3\262\0%S:\r"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "()Lcom/ms/xml/om/Element;\1\0\10toSc"..., 52) = 52
gettimeofday({1058644307, 427816}, NULL) = 0
write(3,
"PK\3\4\n\0\0\0\0\0\\\201e%M\37A\0\302\6\0\0\302\6\0\0\37"...,
32768) = 32768
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\200\0\0", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5,
"\1\0\6<init>\f\0\32\0\31\n\0\10\0\33\1\0\31()Ljava/ut"...,
4036) = 4036
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "ce\f\0D\0C\n\0\6\0E\1\0\25(I)Ljava/lang/Str"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 810000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\1\300\1\0002(Lcom/ms/xml/util/Name;)Lco"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\34\0\r\t\0\6\0\35\1\0\25com.ms.xml.om.Elemen"..., 4092) =
4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "ity\f\0\256\0\255\n\0d\0\257\1\0\26Couldn\'t find en"...,
4092)
= 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "O\260\22\177\260\22\320\260\22\322\260\22\324\260\22\271"...,
4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 930000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "rite\f\0000\0/\n\0)\0001\1\0\1)\10\0003\1\0:(Lcom/ms"...,
4092)
= 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, " Expected closing tag.\10\0\272\1\0\r\'.  "..., 4092) =
4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\5\2\0L+\260\0\0\0\0\0\1\1\f\1c\0\2\0\4\0\0\0\4\0\1\1e"...,
88)
= 88
gettimeofday({1058644307, 697381}, NULL) = 0
write(3,
"\1\0\6<init>\f\0\32\0\31\n\0\10\0\33\1\0\31()Ljava/ut"...,
32768) = 32768
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\0\200\0\0", 4)                = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5,
"\266\1\n\261*\264\0\363\306\0\26*\264\0\363\300\0&\264"...,
4000) = 4000
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 830000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\t\t\0\6\0c\1\0\4apos\10\0e\1\0*(Ljava/lang/S"..., 4092) =
4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\306\0\16*\264\0006-\1\271\0\330\3\0+\271\0\213\1\0\232"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 940000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, " declaration\10\1=\1\0007(Lcom/ms/xml/u"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\254\0\0\0\0\0\1\0\2\0\0\0\2\0%PK\3\4\n\0\0\0\0\0\\\201"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 780000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "next\f\0\16\0\r\t\0\6\0\17\1\0\1I\1\0\4line\f\0\22\0\21"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "til/Atom;)V\1\0\22writeQualifiedName"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "Name;I)V\1\0\4push\f\0\331\0\330\n\0\6\0\332\1\0\32(Lc"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "t been declared yet\10\2p\1\0\10setNDAT"..., 124) = 124
gettimeofday({1058644308, 157361}, NULL) = 0
write(3,
"\266\1\n\261*\264\0\363\306\0\26*\264\0\363\300\0&\264"...,
32768) = 32768
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\31c\0\0", 4)                  = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\f\2\200\0\34\t\0\6\2\201\t\0\273\2\201\1\0\10nameNAME"..., 
3964) = 3964
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 980000})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\223\n\0\6\3\350\1\0\fparseAttList\f\3\352\2\227\n\0\\"..., 
4092) = 4092
select(6, [5], NULL, NULL, {60, 0}) = 1 (in [5], left {59, 930000})
read(5, "\374\17\0\7", 4) = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "TNAME\3\0\0\0\20\1\0\22nameSpaceSeparator\3"..., 4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5,
"\0\0\0\0\0\20\3A\0y\0\2\0\4\0\0\0\4\0\1\3\'\0\1\0\0\0h"...,
4092) = 4092
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5, "\374\17\0\7", 4)               = 4
select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
read(5,
"|\22\260\266\0\202*\20>\266\0\264\266\0\202\23\2\f\266"...,
4092) = 2662
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
select(6, [5], NULL, NULL, {60, 0}

--- eof: strace.out.3341 (child rsync on linux machine ---



And finally, here is the tail end of the strace on the cygwin box:

--- strace.out (on cygwin) ---
   364    8583 [main] rsync 2036 lstat64: entering
   289    8872 [main] rsync 2036 normalize_posix_path: src 
system32/dllcache/comd
lg32.dll
   255    9127 [main] rsync 2036 cwdstuff::get: posix /cygdrive/c/WINNT
   248    9375 [main] rsync 2036 cwdstuff::get: (/cygdrive/c/WINNT) = 
cwdstuff::g
et (0x22EE20, 260, 1, 0), errno 0
   253    9628 [main] rsync 2036 normalize_posix_path: 
/cygdrive/c/WINNT/system32
/dllcache/comdlg32.dll = normalize_posix_path 
(system32/dllcache/comdlg32.dll)
   253    9881 [main] rsync 2036 mount_info::conv_to_win32_path: 
conv_to_win32_pa
th (/cygdrive/c/WINNT/system32/dllcache/comdlg32.dll)
   592   10473 [main] rsync 2036 mount_info::cygdrive_win32_path: src 
'/cygdrive/
c/WINNT/system32/dllcache/comdlg32.dll', dst 
'c:\WINNT\system32\dllcache\comdlg3
2.dll'
   206   10679 [main] rsync 2036 set_flags: flags: binary (0x2)
   186   10865 [main] rsync 2036 mount_info::conv_to_win32_path: 
src_path /cygdri
ve/c/WINNT/system32/dllcache/comdlg32.dll, dst 
c:\WINNT\system32\dllcache\comdlg
32.dll, flags 0x22, rc 0
   651   11516 [main] rsync 2036 symlink_info::check: not a symlink
   201   11717 [main] rsync 2036 symlink_info::check: 0 = symlink.check 
(c:\WINNT
\system32\dllcache\comdlg32.dll, 0x22EAE0) (0x22)
   200   11917 [main] rsync 2036 path_conv::check: root_dir(c:\), 
this->path(c:\W
INNT\system32\dllcache\comdlg32.dll), set_has_acls(8)
   209   12126 [main] rsync 2036 dtable::build_fhandler: fd -1, fh 
0x615F1B90
   194   12320 [main] rsync 2036 stat_worker: 
(system32/dllcache/comdlg32.dll, 0x
22F360, 1, 0x22EFC0), file_attributes 2080
   197   12517 [main] rsync 2036 fhandler_base::open: 
(c:\WINNT\system32\dllcache
\comdlg32.dll, 0x110000) query_open 0
   302   12819 [main] rsync 2036 fhandler_base::open: 0x100 = CreateFile 
(c:\WINN
T\system32\dllcache\comdlg32.dll, 0x80000000, 0x7, 0x22EED0, 0x3, 
0x2000081, 0)
   207   13026 [main] rsync 2036 fhandler_base::set_flags: flags 
0x110000, suppli
ed_bin 0x10000
   189   13215 [main] rsync 2036 fhandler_base::set_flags: 
O_TEXT/O_BINARY set in
...skipping...
096)
   193 29378730 [main] rsync 2036 writev: 4096 = write (1, 0x226AC0, 1), 
errno 0
   223 29378953 [main] rsync 2036 cygwin_select: 2, 0x0, 0x226B00, 0x0, 
0x226AF0
   382 29379335 [main] rsync 2036 dtable::select_write: /dev/pipew fd 1
   182 29379517 [main] rsync 2036 cygwin_select: to->tv_sec 60, 
to->tv_usec 0, ms
  60000
   238 29379755 [main] rsync 2036 cygwin_select: sel.always_ready 1
  1457 29381212 [main] rsync 2036 select_stuff::cleanup: calling cleanup 
routines
   214 29381426 [main] rsync 2036 set_bits: me 0xA0E5FD0, testing fd 1 
(/dev/pipe
w)
   395 29381821 [main] rsync 2036 set_bits: ready 1
   190 29382011 [main] rsync 2036 select_stuff::poll: returning 1
   185 29382196 [main] rsync 2036 select_stuff::cleanup: calling cleanup 
routines
   182 29382378 [main] rsync 2036 select_stuff::~select_stuff: deleting 
select re
cords
   342 29382720 [main] rsync 2036 writev: writev (1, 0x226AC0, 1)
   184 29382904 [main] rsync 2036 fhandler_base::write: binary write
   222 29383126 [main] rsync 2036 fhandler_base::write: 4096 = write 
(0x226B30, 4
096)
   193 29383319 [main] rsync 2036 writev: 4096 = write (1, 0x226AC0, 1), 
errno 0
   217 29383536 [main] rsync 2036 cygwin_select: 2, 0x0, 0x226B00, 0x0, 
0x226AF0
   351 29383887 [main] rsync 2036 dtable::select_write: /dev/pipew fd 1
   181 29384068 [main] rsync 2036 cygwin_select: to->tv_sec 60, 
to->tv_usec 0, ms
  60000
   183 29384251 [main] rsync 2036 cygwin_select: sel.always_ready 1
   181 29384432 [main] rsync 2036 select_stuff::cleanup: calling cleanup 
routines
   184 29384616 [main] rsync 2036 set_bits: me 0xA0E5FD0, testing fd 1 
(/dev/pipe
w)
   183 29384799 [main] rsync 2036 set_bits: ready 1
   179 29384978 [main] rsync 2036 select_stuff::poll: returning 1
   181 29385159 [main] rsync 2036 select_stuff::cleanup: calling cleanup 
routines
   181 29385340 [main] rsync 2036 select_stuff::~select_stuff: deleting 
select re
cords
   338 29385678 [main] rsync 2036 writev: writev (1, 0x226AC0, 1)
   184 29385862 [main] rsync 2036 fhandler_base::write: binary write

--- eof: strace.out (on cygwin machine) ---


This particular run created a partial directory structure and one file:
./$NtServicePackUninstall$/2jl3vz7j.zip

Any ideas?

Thanks,
Jeff

jw schultz

2003-Jul-22 09:24 UTC

head link

rsync on cygwin is hanging

On Sat, Jul 19, 2003 at 04:06:54PM -0400, Jeff Hedlund
wrote:> 
> I am trying to backup a Windows machine to a Linux machine using rsync 
> (over ssh).
> 
> I have been able to successfully sync a few files at a time, but when I 
> try to backup an entire tree the process hangs on both machines.
> 
> I believe this is a different problem than one posted on here before, 
> but I did try this patch with no success: 
> http://www.mail-archive.com/rsync@lists.samba.org/msg07525.html
> 
> When I try to rsync a tree, it will usually create a partial directory 
> structure and download a few files and then hang.
> 
> Linux:  RH 7.3, rsync 2.5.6, OpenSSH_3.1p1
> Cygwin: 5.0, rsync 2.5.6, OpenSSH_3.6.1p1
> 
> Let me know if more information is needed (and I apologize if too much 
> is sent) :).
> 
> I am running the following on the linux machine:
> rsync -vvrltDH --numeric-ids -pgo --stats --exclude-from=exclude.list 
> --rsh="ssh" user@host:c:/WINNT/ tree
It looks like the rsync server on cygwin is failing to
return from a write to stdout.

I know (from prior offline discussion) you are using ssh key
based command restriction via a wrapper script on the linux
rsync servers.  Are you using a ssh key based command
restriction here as well?  You don't mention that anywhere
in this email.

If you are running rsync via a wrapper script does the
script exec the rsync command or run it as a child process?

I trimmed the traces a bit to better reflect suitable
inlining.  You have a bad problem with linewrap messing up
the traces.

See my comments below and the cygwin annotations.  This is
the first time i've seen a cygwin strace. Interesting.
> 
> Here is the tail end of the output from that command where it hangs:
> 
> --- rsync.out ---
> system32/wbem/mof/
> system32/wbem/mof/bad/
> system32/wbem/mof/good/
> system32/wins/
> twain_32/
> twain_32/fjscan/
> twain_32/fjscan/fcpa/
> twain_32/logiscan/
> twain_32/miitwain/
> wudnld20.tmp/
> --- eof: rsync.out ---
> 
> Here is the strace on the linux machine where it hangs:
> 
> --- strace.out (on linux machine) ---
[snip]> lstat64("winrep.exe", 0xbffffa20)       = -1 ENOENT (No such file
or
> directory)
> lstat64("wudnld20.tmp", 0xbffffa20)     = -1 ENOENT (No such file
or
> directory)
> mkdir("wudnld20.tmp", 040700)       = 0
> lstat64("wudnld20.tmp", {st_mode=S_IFDIR|0700, st_size=4096,
...}) = 0
> time(NULL)                              = 1058644307
> utime("wudnld20.tmp", [2003/07/19-15:51:47, 2002/01/25-01:17:08])
= 0
> lchown32(0x8074940, 0x220, 0x201)       = 0
> write(1, "wudnld20.tmp/\n", 14)         = 14
> select(8, [7], [4], NULL, {60, 0})      = 1 (out [4], left {60, 0})
> write(4,
"\274\2\0\0\0\0\0\0\202\36\0\0\0\0\0\0\274\2\0\0\0\0\0\0"...,
> 3916)
>    = 3916
> select(8, [3 7], NULL, NULL, {60, 0})       = 0 (Timeout)
> select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
> select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
> select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
> select(8, [3 7], NULL, NULL, {60, 0})     = 0 (Timeout)
> select(8, [3 7], NULL, NULL, {60, 0}
> --- eof: strace.out (on linux machine) ---
Waiting for another process to send something.
> Here is the output of the child rsync process on the linux box:
> 
> --- strace.out.3341 (child rsync on linux machine) ---
[snip]> select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
> read(5, "TNAME\3\0\0\0\20\1\0\22nameSpaceSeparator\3"..., 4092) =
4092
> select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
> read(5, "\374\17\0\7", 4)               = 4
> select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
> read(5,
"\0\0\0\0\0\20\3A\0y\0\2\0\4\0\0\0\4\0\1\3\'\0\1\0\0\0h"...,
> 4092) = 4092
> select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
> read(5, "\374\17\0\7", 4)               = 4
> select(6, [5], NULL, NULL, {60, 0})     = 1 (in [5], left {60, 0})
> read(5,
"|\22\260\266\0\202*\20>\266\0\264\266\0\202\23\2\f\266"...,
> 4092) = 2662
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0})   = 0 (Timeout)
> select(6, [5], NULL, NULL, {60, 0}
> 
> --- eof: strace.out.3341 (child rsync on linux machine ---
Waiting for another process to send something.
> And finally, here is the tail end of the strace on the cygwin box:
> 
> --- strace.out (on cygwin) ---[snip and cleaned up to fix line-wrap errors]
start of write()>   342 29382720 [main] rsync 2036 writev: writev (1, 0x226AC0, 1)
>   184 29382904 [main] rsync 2036 fhandler_base::write: binary write
>   222 29383126 [main] rsync 2036 fhandler_base::write: 4096 = write
(0x226B30, 4 096)
>   193 29383319 [main] rsync 2036 writev: 4096 = write (1, 0x226AC0, 1),
errno 0completion of write()
	good to know what that looks like
>   217 29383536 [main] rsync 2036 cygwin_select: 2, 0x0, 0x226B00, 0x0,
0x226AF0
>   351 29383887 [main] rsync 2036 dtable::select_write: /dev/pipew fd 1
>   181 29384068 [main] rsync 2036 cygwin_select: to->tv_sec 60,
to->tv_usec 0, ms  60000
>   183 29384251 [main] rsync 2036 cygwin_select: sel.always_ready 1
>   181 29384432 [main] rsync 2036 select_stuff::cleanup: calling cleanup
routines
>   184 29384616 [main] rsync 2036 set_bits: me 0xA0E5FD0, testing fd 1
(/dev/pipew)
>   183 29384799 [main] rsync 2036 set_bits: ready 1
>   179 29384978 [main] rsync 2036 select_stuff::poll: returning 1
>   181 29385159 [main] rsync 2036 select_stuff::cleanup: calling cleanup
routines
>   181 29385340 [main] rsync 2036 select_stuff::~select_stuff: deleting
select records
start of write()>   338 29385678 [main] rsync 2036 writev: writev (1, 0x226AC0, 1)
>   184 29385862 [main] rsync 2036 fhandler_base::write: binary write
> 
> --- eof: strace.out (on cygwin machine) ---Ahh, write didn't complete.  We have an i/o error outside of rsync.
> 
> 
> This particular run created a partial directory structure and one file:
> ./$NtServicePackUninstall$/2jl3vz7j.zip
> 
> Any ideas?
> 
> Thanks,
> Jeff
-- 
________________________________________________________________
	J.W. Schultz            Pegasystems Technologies
	email address:		jw@pegasys.ws

		Remember Cernan and Schmitt

Maybe Matching Threads

Search for more possibly parallel threads

rsync - Jul 2003 - rsync on cygwin is hanging

rsync on cygwin is hanging

rsync on cygwin is hanging

Maybe Matching Threads