connection unexpectedly closed

Matthias P. Wuerfl mpw at taquiri.de
Tue Dec 19 17:51:15 GMT 2006


Hi.

I have a Windows machine (XP/SP2) which i want backup to a Linux machine 
(named "Pippi") using rsync. I have cwRsyncServer running on the Windows 
machine (10.10.10.90). When i start rsync from the Linux machine after a 
while rsync stops - as far as i understand it because of a network timeout.

> io timeout after 60 seconds -- exiting
> rsync error: timeout in data send/receive (code 30) at io.c(171) [receiver=2.6.8]
> _exit_cleanup(code=30, file=io.c, line=171): about to call exit(30)
> rsync: connection unexpectedly closed (1179625 bytes received so far) [generator]
> rsync error: error in rsync protocol data stream (code 12) at io.c(463) [generator=2.6.8]
> _exit_cleanup(code=12, file=io.c, line=463): about to call exit(12)

This occours always after ~1180000 Bytes, not at a certain file. During 
the last 60 seconds a netstat on the Linux bos says:

> tcp 0  13442 pippi:54779 10.10.10.90:ssh   ESTABLISHED
> tcp 0   4594 pippi:59693 10.10.10.90:ssh   FIN_WAIT1

...and on the Windows box:

> Aktive Verbindungen 
>
> Proto Lokale Adresse  Remoteadresse Status            PID
> TCP   taquiri18:22    10.10.10.90:0 ABHÖREN           248  [sshd.exe]
> TCP   taquiri18:22    pippi:54779   HERGESTELLT       248  [sshd.exe] 
> TCP   taquiri18:22    pippi:59693   HERGESTELLT       2640 [System] 
> TCP   taquiri18:22    pippi:38263   SCHLIESSEN_WARTEN 2640 [System] 
> TCP   taquiri18:22    pippi:48734   SCHLIESSEN_WARTEN 2640 [System] 

...where "ABHÖREN" is listening, "HERGESTELL" is established and 
"SCHLIESSEN_WARTEN" means "close_wait".

I adjusted the logging level on the winbox and Pippi and the last event 
in the log is:
(these are not from the same run as the netstat before)

> sshd: PID 1852: debug2: channel 0: window 65516 sent adjust 65556.
(before the just hundreds of "rcvd adjust $NUMBER"-lines)

...and on Pippi:

> select(5, [3], [4], NULL, {60, 0}io timeout after 60 seconds -- exiting
> rsync error: timeout in data send/receive (code 30) at io.c(171) [receiver=2.6.8]
> _exit_cleanup(code=30, file=io.c, line=171): about to call exit(30)
> )      = 2 (in [3], out [4], left {5, 830000})
> --- SIGCHLD (Child exited) @ 0 (0) ---
> waitpid(-1, [{WIFSIGNALED(s) && WTERMSIG(s) == SIGUSR1}], WNOHANG) = 20773
> waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 30}], WNOHANG) = 20774
> waitpid(-1, 0xbff0ffe0, WNOHANG)        = -1 ECHILD (No child processes)
> sigreturn()                             = ? (mask now [])
> select(4, [3], [], NULL, {60, 0})       = 1 (in [3], left {60, 0})
> read(3, "", 4)                          = 0
> write(2, "rsync: connection unexpectedly c"..., 81rsync: connection unexpectedly closed (1179663 bytes received so far) [generator]) = 81
> write(2, "\n", 1
> )                       = 1
> rt_sigaction(SIGUSR1, {SIG_IGN}, NULL, 8) = 0
> rt_sigaction(SIGUSR2, {SIG_IGN}, NULL, 8) = 0
> waitpid(20773, 0xbff0f918, WNOHANG)     = -1 ECHILD (No child processes)
> kill(20773, SIGUSR1)                    = -1 ESRCH (No such process)
> kill(20774, SIGUSR1)                    = -1 ESRCH (No such process)
> write(2, "rsync error: error in rsync prot"..., 89rsync error: error in rsync protocol data stream (code 12) at io.c(463) [generator=2.6.8]) = 89
> write(2, "\n", 1
> )                       = 1
> write(1, "_exit_cleanup(code=12, file=io.c"..., 68_exit_cleanup(code=12, file=io.c, line=463): about to call exit(12)
> ) = 68
> exit_group(12)                          = ?
> Process 20772 detached

To me it seems as if there's something wrong on TCP-level. That's why i 
want to include the following information:

Linux Kernel 2.6.17 (Ubuntu)
No router between the hosts
Windows cwRsyncServer with cygwin 1.5.21

I have no idea where to search for the reasons. Can anyone help me with 
this?

TIA, Matthias


More information about the rsync mailing list