Logging problem

René van der Kroft rene at backup.nl
Fri Aug 8 20:27:00 EST 2003


At 11:36 5-8-2003 -0700, you wrote:
> > Today I received the error again:
> >
> >         Received disconnect from IP: 2: fork failed: Resource temporarily
> > unavailable
> >
> >         rsync: connection unexpectedly closed (0 bytes read so far)
> >         rsync error: error in rsync protocol data stream (code 12) at
> > io.c(150)
> >         rsync error: partial transfer (code 23) at main.c(578)
> >
> > I've checked the system logs to check if the system was out of resouces 
> but
> > I can't find anything.
> > Is there's something else what it could be?
>
>That message is not coming from anywhere in rsync.  This
>must be coming from a library.  I notice a "^M" on the end
>of it.  Either the other end is windows or this message has
>been cooked (stty).
>
>I cannot say what resource is temporarily unavailable.  It
>might have run out of pids or, more likely, memory.  In
>either case if fork failed rsync will quickly exit freeing
>up resources.  If you feel daring you could run a fork bomb
>to confirm that it is fork hitting a limit.



I've add verbose logging to the SSH deamon, and I notice something strange. 
I'am using a public key create a SSH connection, this is working correct. 
The connection is created successfully like the logs shows. As you can see 
the connection disconnections after a few seconds and a new connection is 
started. This repeats itself for almost an hour!!! This is probaly is 
creating a fork limit...

Do you know what could be wrong?

Logfile:

Aug  8 04:00:23 doos sshd[22581]: Connection from 123.456.789.123 port 21408
Aug  8 04:00:25 doos sshd[4488]: Failed none for user from 123.456.789.123 
port 21408 ssh2
Aug  8 04:00:25 doos sshd[22581]: Failed none for user from 123.456.789.123 
port 21408 ssh2
Aug  8 04:00:25 doos sshd[22581]: Found matching DSA key: 
bc:f9:00:ed:29:78:f4:4b:d3:9c:e2:99:f9:31:16:00
Aug  8 04:00:25 doos sshd[4488]: Accepted publickey for user from 
123.456.789.123 port 21408 ssh2
Aug  8 04:00:25 doos sshd[22581]: Accepted publickey for user from 
123.456.789.123 port 21408 ssh2
Aug  8 04:00:43 doos sshd[2772]: Connection closed by 123.456.789.123
Aug  8 04:00:43 doos sshd[2772]: Closing connection to 123.456.789.123

Aug  8 04:00:43 doos sshd[30621]: Connection from 123.456.789.123 port 42870
Aug  8 04:00:44 doos sshd[30621]: Failed none for user from 123.456.789.123 
port 42870 ssh2
Aug  8 04:00:44 doos sshd[28720]: Failed none for user from 123.456.789.123 
port 42870 ssh2
Aug  8 04:00:45 doos sshd[30621]: Found matching DSA key: 
bc:f9:00:ed:29:78:f4:4b:d3:9c:e2:99:f9:31:16:00
Aug  8 04:00:45 doos sshd[28720]: Accepted publickey for user from 
123.456.789.123 port 42870 ssh2
Aug  8 04:00:45 doos sshd[30621]: Accepted publickey for user from 
123.456.789.123 port 42870 ssh2
Aug  8 04:00:47 doos sshd[20405]: Connection closed by 123.456.789.123
Aug  8 04:00:47 doos sshd[20405]: Closing connection to 123.456.789.123

Aug  8 04:01:23 doos sshd[31597]: Connection from 123.456.789.123 port 27324
Aug  8 04:01:24 doos sshd[31597]: Failed none for user from 123.456.789.123 
port 27324 ssh2
Aug  8 04:01:24 doos sshd[10248]: Failed none for user from 123.456.789.123 
port 27324 ssh2
Aug  8 04:01:24 doos sshd[31597]: Found matching DSA key: 
bc:f9:00:ed:29:78:f4:4b:d3:9c:e2:99:f9:31:16:00
Aug  8 04:01:24 doos sshd[10248]: Accepted publickey for user from 
123.456.789.123 port 27324 ssh2
Aug  8 04:01:24 doos sshd[31597]: Accepted publickey for user from 
123.456.789.123 port 27324 ssh2
Aug  8 04:01:32 doos sshd[1537]: Connection closed by 123.456.789.123
Aug  8 04:01:32 doos sshd[1537]: Closing connection to 123.456.789.123




More information about the rsync mailing list