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