rsync gives me error 12

rsync gives me error 12

[Neil Aggarwal]

Hello:



I am trying to use rsync to pull all backups from a linux server
to my local Windows machine.



I tried this rsync command:

$ rsync --debug=ALL -e ssh root@marketing.propfinancing.com:/var/www/svnDumps
/cygdrive/c/Tmp



Here is the output:

opening connection using: ssh -l root marketing.propfinancing.com rsync
--server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)

rsync: connection unexpectedly closed (0 bytes received so far) [sender]

rsync error: error in rsync protocol data stream (code 12) at io.c(226)
[sender=3.1.3]

rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]

rsync error: error in rsync protocol data stream (code 12) at io.c(228)
[Receiver=3.2.4dev]

[Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
exit(12)



From what I can find online, code 12 is insufficient disk space, missing
remote
rsync, or a connection error.   I don’t think any of those are the case
here and the
debug output is not helping me.



Any ideas what could be happening?



Thank you,

  Neil



--

Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com

We offer 30 year loans on single family houses!

Re: rsync gives me error 12

[Adam Dinwoodie]

On Thu, Mar 24, 2022 at 12:22:12AM -0500, Neil Aggarwal wrote:
> I am trying to use rsync to pull all backups from a linux server
> to my local Windows machine.
> 
> I tried this rsync command:
> 
> $ rsync --debug=ALL -e ssh root@marketing.propfinancing.com:/var/www/svnDumps
> /cygdrive/c/Tmp
> 
> Here is the output:
> 
> opening connection using: ssh -l root marketing.propfinancing.com rsync
> --server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)
> 
> rsync: connection unexpectedly closed (0 bytes received so far) [sender]
> 
> rsync error: error in rsync protocol data stream (code 12) at io.c(226)
> [sender=3.1.3]
> 
> rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
> 
> rsync error: error in rsync protocol data stream (code 12) at io.c(228)
> [Receiver=3.2.4dev]
> 
> [Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
> exit(12)
> 
> From what I can find online, code 12 is insufficient disk space, missing
> remote
> rsync, or a connection error.   I don’t think any of those are the case
> here and the
> debug output is not helping me.
> 
> Any ideas what could be happening?

I suspect the `-e ssh` part is the problem here.  That's supposed to
specify the remote shell, not the method for connecting.  What are you
attempting to achieve with that?

RE: rsync gives me error 12

[Neil Aggarwal]

> I suspect the `-e ssh` part is the problem here.  That's supposed to
> specify the remote shell, not the method for connecting.  What are you
> attempting to achieve with that?

I am trying to do the sync over an SSH connection.

When I remove it:
rsync --debug=ALL root@marketing.propfinancing.com:/var/www/svnDumps
/cygdrive/c/Tmp

I still get the error:
opening connection using: ssh -l root marketing.propfinancing.com
rsync --server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)
rsync: connection unexpectedly closed (0 bytes received so far) [sender]
rsync error: error in rsync protocol data stream (code 12) at io.c(226)
[sender=3.1.3]
rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
rsync error: error in rsync protocol data stream (code 12) at io.c(228)
[Receiver=3.2.4dev]
[Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
exit(12)

Thanks,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

Re: rsync gives me error 12

[Adam Dinwoodie]

On Fri, Mar 25, 2022 at 08:25:16AM -0500, Neil Aggarwal wrote:
> > I suspect the `-e ssh` part is the problem here.  That's supposed to
> > specify the remote shell, not the method for connecting.  What are you
> > attempting to achieve with that?
> 
> I am trying to do the sync over an SSH connection.
> 
> When I remove it:
> rsync --debug=ALL root@marketing.propfinancing.com:/var/www/svnDumps
> /cygdrive/c/Tmp
> 
> I still get the error:
> opening connection using: ssh -l root marketing.propfinancing.com
> rsync --server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)
> rsync: connection unexpectedly closed (0 bytes received so far) [sender]
> rsync error: error in rsync protocol data stream (code 12) at io.c(226)
> [sender=3.1.3]
> rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
> rsync error: error in rsync protocol data stream (code 12) at io.c(228)
> [Receiver=3.2.4dev]
> [Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
> exit(12)

Works just fine for me.  (Indeed, it works just fine even if I add `-e
ssh`, which surprised me -- clearly I have the wrong impression of what
that `-e` argument is for!)

Looking slightly closer at the logs, this reads to me like the remote
server is closing the connection, so I expect you'll need help from
whoever provides/supports that remote server to work out why it's doing
that.

RE: rsync gives me error 12

[Neil Aggarwal]

Adam:

> Looking slightly closer at the logs, this reads to me like the remote
> server is closing the connection, so I expect you'll need help from
> whoever provides/supports that remote server to work out why it's doing
> that.

I am responsible for the remote server.  I will look into that.
Thank you for putting me on the right track!

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

RE: rsync gives me error 12

[Neil Aggarwal]

Adam:

> this reads to me like the remote
> server is closing the connection

When I run this command on my Windows machine:
rsync --debug=ALL root@marketing.propfinancing.com:/var/www/svnDumps
/cygdrive/c/Tmp

I get this output on the screen:
opening connection using: ssh -l root marketing.propfinancing.com rsync
--server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)
rsync: connection unexpectedly closed (0 bytes received so far) [sender]
rsync error: error in rsync protocol data stream (code 12) at io.c(226)
[sender=3.1.3]
rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
rsync error: error in rsync protocol data stream (code 12) at io.c(228)
[Receiver=3.2.4dev]
[Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
exit(12)

It is connecting to the Linux server and immediately disconnecting, here
is what I see in /var/log/secure:
Mar 25 12:11:00 marketing sshd[43597]: Postponed publickey for root from
99.137.191.183 port 64715 ssh2 [preauth]
Mar 25 12:11:00 marketing sshd[43597]: Accepted publickey for root from
99.137.191.183 port 64715 ssh2: RSA SHA256:[removed]
Mar 25 12:11:00 marketing sshd[43597]: pam_unix(sshd:session): session
opened for user root by (uid=0)
Mar 25 12:11:00 marketing sshd[43600]: Received disconnect from
99.137.191.183 port 64715:11: disconnected by user
Mar 25 12:11:00 marketing sshd[43600]: Disconnected from user root
99.137.191.183 port 64715
Mar 25 12:11:00 marketing sshd[43597]: pam_unix(sshd:session): session
closed for user root

I'm stumped at this point.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

RE: rsync gives me error 12

[Neil Aggarwal]

Adam:

I tried doing an upload using this command:
rsync test.xml root@marketing.propfinancing.com:/tmp

I still get an error:
rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
rsync error: error in rsync protocol data stream (code 12) at io.c(226)
[Receiver=3.1.3]
rsync: connection unexpectedly closed (0 bytes received so far) [sender]
rsync error: error in rsync protocol data stream (code 12) at io.c(228)
[sender=3.2.4dev]

Could it be the rsync versions are somehow incompatible?

Would it help if I create a login for you on the server so
you can try doing an rsync yourself?

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

Re: rsync gives me error 12

[Wayne Davison]

On Fri, Mar 25, 2022 at 10:37 AM Neil Aggarwal wrote:
> I still get an error:
> rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
> rsync error: error in rsync protocol data stream (code 12) at io.c(226)
> [Receiver=3.1.3]
> rsync: connection unexpectedly closed (0 bytes received so far) [sender]
> rsync error: error in rsync protocol data stream (code 12) at io.c(228)
> [sender=3.2.4dev]

As the rsync issues & debugging page mentions, you should try forcing
errors to stderr to see if it makes the remote rsync report its error
to you:

    --msgs2stderr -M--msgs2stderr

It also mentions an rsync-debug script that you can put on the remote
host and use to get the strace output of what is happening and get a
core dump (if it is dumping core).

    https://rsync.samba.org/issues.html

Also, try running the following test command and make sure you see the
word "hi" and nothing else:

     echo hi | ssh root@marketing.propfinancing.com cat

..wayne..

RE: rsync gives me error 12

[Neil Aggarwal]

[-- Attachment #1: Type: text/plain, Size: 1362 bytes --]

Wayne:

When I run this command on my Windows machine:
echo hi | ssh root@marketing.propfinancing.com cat

I get back:
hi

When I run this command:
 rsync --debug=ALL --msgs2stderr -M--msgs2stderr
root@marketing.propfinancing.com:/var/www/svnDumps /cygdrive/c/Tmp

I get this output:
opening connection using: ssh -l root marketing.propfinancing.com
rsync --server --sender -e.LsfxCIvu --msgs2stderr --msgs2stderr .
/var/www/svnDumps  (12 args)
rsync: connection unexpectedly closed (0 bytes received so far) [sender]
rsync error: error in rsync protocol data stream (code 12) at io.c(226)
[sender=3.1.3]
rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
rsync error: error in rsync protocol data stream (code 12) at io.c(228)
[Receiver=3.2.4dev]
[Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
exit(12)

I put the rsync-debug script in /tmp on the server and then ran this command
on my Windows
machine:
rsync -av --rsync-path=/tmp/rsync-debug
root@marketing.propfinancing.com:/var/www/svnDumps /cygdrive/c/Tmp

I got the same output on my local machine.

On the server, I got a /tmp/rsync-3191.out file which I am attaching here.
I can't make sense of what it is trying to tell me.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

[-- Attachment #2: rsync-3191.out --]
[-- Type: application/octet-stream, Size: 15885 bytes --]

3205  23:51:38 execve("/usr/bin/rsync", ["rsync", "--server", "--sender", "-vlogDtpre.iLsfxCIvu", ".", "/var/www/svnDumps"], 0x7ffdfe4cb8b8 /* 20 vars */) = 0
3205  23:51:38 brk(NULL)                = 0x5635a1c8c000
3205  23:51:38 arch_prctl(0x3001 /* ARCH_??? */, 0x7ffc731e1340) = -1 EINVAL (Invalid argument)
3205  23:51:38 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
3205  23:51:38 openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0644, st_size=21163, ...}) = 0
3205  23:51:38 mmap(NULL, 21163, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd592424000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/lib64/libattr.so.1", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300\26\0\0\0\0\0\0@\0\0\0\0\0\0\0\20a\0\0\0\0\0\0\0\0\0\0@\08\0\10\0@\0\35\0\34\0\1\0\0\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0X?\0\0\0\0\0\0X?\0\0\0\0\0\0\0\0 \0\0\0\0\0\1\0\0\0\6\0\0\0PL\0\0\0\0\0\0PL \0\0\0\0\0PL \0\0\0\0\0\260\3\0\0\0\0\0\0\300\3\0\0\0\0\0\0\0\0 \0\0\0\0\0\2\0\0\0\6\0\0\0hL\0\0\0\0\0\0hL \0\0\0\0\0hL \0\0\0\0\0\20\2\0\0\0\0\0\0\20\2\0\0\0\0\0\0\10\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\0\2\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0\2\0\0\0\0\0\0$\0\0\0\0\0\0\0$\0\0\0\0\0\0\0\4\0\0\0\0\0\0\0\4\0\0\0\4\0\0\08?\0\0\0\0\0\08?\0\0\0\0\0\08?\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0P\345td\4\0\0\0d8\0\0\0\0\0\0d8\0\0\0\0\0\0d8\0\0\0\0\0\0\4\1\0\0\0\0\0\0\4\1\0\0\0\0\0\0\4\0\0\0\0\0\0\0Q\345td\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0R\345td\4\0\0\0PL\0\0\0\0\0\0PL \0\0\0\0\0PL \0\0\0\0\0\260\3\0\0\0\0\0\0\260\3\0\0\0\0\0\0\1\0\0\0\0\0\0\0\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\230\330\274\3Z\350k\201\264\7\237}O\360\6\223\21\243K#\0\0\0\0\21\0\0\0$\0\0\0\4\0\0\0\10\0\0\0*\0\30\0(\340\207\20\24\4\1bH X \4\210#\0\3\21\10\311\20\230\0\f\0\5\4\200$\0\0\0%\0\0\0)\0\0\0,\0\0\0/\0\0\0\0\0\0\0000\0\0\0002\0\0\0004\0\0\0006\0\0\0\0\0\0\08\0\0\0\0\0\0\0;\0\0\0<\0\0\0>\0\0\0A\0\0\0\241\213\315\300\4\301X\25\276\250\343\370\2-^\203m42\350\352\32\346\300X\235\235\323\277B1\340f\336\10`P\3431\256\25\345N\251s\302x\356.cx\204\221U_\25J=G\363/cx\2040cx\204\221x\251\335\270\3732\0001cx\2042S\247\273\362?\355s\241sU\241\313\333\343\370D\366H\0237|h%\304\350\33\233\332\217[\25\333\f\26\211\255\179N\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\216\0\0\0\22\0\0\0\0\0\0\0\0\0\0\0", 832) = 832
3205  23:51:38 lseek(3, 16184, SEEK_SET) = 16184
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0755, st_size=26704, ...}) = 0
3205  23:51:38 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fd592422000
3205  23:51:38 lseek(3, 16184, SEEK_SET) = 16184
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 mmap(NULL, 2117648, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd591ff8000
3205  23:51:38 mprotect(0x7fd591ffc000, 2097152, PROT_NONE) = 0
3205  23:51:38 mmap(0x7fd5921fc000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7fd5921fc000
3205  23:51:38 mmap(0x7fd5921fd000, 16, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fd5921fd000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/lib64/libacl.so.1", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\37\0\0\0\0\0\0@\0\0\0\0\0\0\0\10\340\0\0\0\0\0\0\0\0\0\0@\08\0\10\0@\0\36\0\35\0\1\0\0\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0 s\0\0\0\0\0\0 s\0\0\0\0\0\0\0\0 \0\0\0\0\0\1\0\0\0\6\0\0\0\310{\0\0\0\0\0\0\310{ \0\0\0\0\0\310{ \0\0\0\0\0\210\4\0\0\0\0\0\0\270\4\0\0\0\0\0\0\0\0 \0\0\0\0\0\2\0\0\0\6\0\0\0\340{\0\0\0\0\0\0\340{ \0\0\0\0\0\340{ \0\0\0\0\0 \2\0\0\0\0\0\0 \2\0\0\0\0\0\0\10\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\0\2\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0\2\0\0\0\0\0\0$\0\0\0\0\0\0\0$\0\0\0\0\0\0\0\4\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\0s\0\0\0\0\0\0\0s\0\0\0\0\0\0\0s\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0P\345td\4\0\0\0`d\0\0\0\0\0\0`d\0\0\0\0\0\0`d\0\0\0\0\0\0004\2\0\0\0\0\0\0004\2\0\0\0\0\0\0\4\0\0\0\0\0\0\0Q\345td\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0R\345td\4\0\0\0\310{\0\0\0\0\0\0\310{ \0\0\0\0\0\310{ \0\0\0\0\08\4\0\0\0\0\0\08\4\0\0\0\0\0\0\1\0\0\0\0\0\0\0\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\260\3678\315\261\355\236\24\361\27\360cs\232\232O\232\375\2453\0\0\0\0%\0\0\0,\0\0\0\4\0\0\0\10\0\0\0\5\320(2\n\4\4\10H@\35\201\277U\0000\tB\214\333\0\225H\2006@\234\fR\10\1n\0\0\0\0,\0\0\0.\0\0\0000\0\0\0002\0\0\0\0\0\0\0003\0\0\0007\0\0\0\0\0\0\09\0\0\0:\0\0\0\0\0\0\0;\0\0\0@\0\0\0A\0\0\0B\0\0\0C\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0D\0\0\0E\0\0\0G\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0I\0\0\0J\0\0\0K\0\0\0L\0\0\0\0\0\0\0N\0\0\0\0\0\0\0\0\0\0\0P\0\0\0Q\0\0\0T\0\0\0R\240\36\321i#J\310P\352A\355\231h\247\255\374+\31\376\377\205H\321\3\373\226\377\230\203-T\30SQ2\332\327\326\277!\216Z,\322B\24\351\235\257\256BmF\3045\301{\372\365\24\34\346s|\342\252\317\16\214O\310\276\336u\236\305f\215\v_CQ2", 832) = 832
3205  23:51:38 lseek(3, 29440, SEEK_SET) = 29440
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0755, st_size=59272, ...}) = 0
3205  23:51:38 lseek(3, 29440, SEEK_SET) = 29440
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 mmap(NULL, 2130048, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd591def000
3205  23:51:38 mprotect(0x7fd591df7000, 2093056, PROT_NONE) = 0
3205  23:51:38 mmap(0x7fd591ff6000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7fd591ff6000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/lib64/libpopt.so.0", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340*\0\0\0\0\0\0@\0\0\0\0\0\0\0\30\316\0\0\0\0\0\0\0\0\0\0@\08\0\t\0@\0\36\0\35\0\1\0\0\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\250\264\0\0\0\0\0\0\250\264\0\0\0\0\0\0\0\0 \0\0\0\0\0\1\0\0\0\6\0\0\0\320\272\0\0\0\0\0\0\320\272 \0\0\0\0\0\320\272 \0\0\0\0\0P\7\0\0\0\0\0\0\240\7\0\0\0\0\0\0\0\0 \0\0\0\0\0\2\0\0\0\6\0\0\0\350\272\0\0\0\0\0\0\350\272 \0\0\0\0\0\350\272 \0\0\0\0\0\20\2\0\0\0\0\0\0\20\2\0\0\0\0\0\0\10\0\0\0\0\0\0\0\4\0\0\0\4\0\0\08\2\0\0\0\0\0\08\2\0\0\0\0\0\08\2\0\0\0\0\0\0$\0\0\0\0\0\0\0$\0\0\0\0\0\0\0\4\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\210\264\0\0\0\0\0\0\210\264\0\0\0\0\0\0\210\264\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0S\345td\4\0\0\0\210\264\0\0\0\0\0\0\210\264\0\0\0\0\0\0\210\264\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0P\345td\4\0\0\0|\241\0\0\0\0\0\0|\241\0\0\0\0\0\0|\241\0\0\0\0\0\0l\2\0\0\0\0\0\0l\2\0\0\0\0\0\0\4\0\0\0\0\0\0\0Q\345td\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0R\345td\4\0\0\0\320\272\0\0\0\0\0\0\320\272 \0\0\0\0\0\320\272 \0\0\0\0\0000\5\0\0\0\0\0\0000\5\0\0\0\0\0\0\1\0\0\0\0\0\0\0\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\330\31\311\336\3738j\305\214\255\6\220\272\241c\366\375w\3?\0\0\0\0%\0\0\0A\0\0\0\10\0\0\0\t\0\0\0\0\20\0X\24\n\200$\30\0\0p\200\1\0\2\204\205\20P\2\224A\2 @\240\0\221\f\10 \10A\0 \10\20)\10\20&@\0@-\2\201\330\0\200\240\311\t\0\r\200 \0\0\10\202  A\0\0\0B\0\0\0C\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0E\0\0\0\0\0\0\0\0\0\0\0G\0\0\0\0\0\0\0\0\0\0\0J\0\0\0K\0\0\0L\0\0\0N\0\0\0R\0\0\0U\0\0\0V\0\0\0W\0\0\0X\0\0\0^\0\0\0a\0\0\0d\0\0\0g\0\0\0i\0\0\0\0\0\0\0j\0\0\0l\0\0\0m\0\0\0o\0\0\0\0\0\0\0p\0\0\0r\0\0\0", 832) = 832
3205  23:51:38 lseek(3, 46216, SEEK_SET) = 46216
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0755, st_size=54680, ...}) = 0
3205  23:51:38 lseek(3, 46216, SEEK_SET) = 46216
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 mmap(NULL, 2146928, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd591be2000
3205  23:51:38 mprotect(0x7fd591bee000, 2093056, PROT_NONE) = 0
3205  23:51:38 mmap(0x7fd591ded000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xb000) = 0x7fd591ded000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\2405\2\0\0\0\0\0@\0\0\0\0\0\0\0xF0\0\0\0\0\0\0\0\0\0@\08\0\f\0@\0L\0K\0\6\0\0\0\4\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0\240\2\0\0\0\0\0\0\240\2\0\0\0\0\0\0\10\0\0\0\0\0\0\0\3\0\0\0\4\0\0\0\220\v\31\0\0\0\0\0\220\v\31\0\0\0\0\0\220\v\31\0\0\0\0\0\34\0\0\0\0\0\0\0\34\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0\1\0\0\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0004\264\33\0\0\0\0\0004\264\33\0\0\0\0\0\0\0 \0\0\0\0\0\1\0\0\0\6\0\0\0p\267\33\0\0\0\0\0p\267;\0\0\0\0\0p\267;\0\0\0\0\0xP\0\0\0\0\0\0\320\217\0\0\0\0\0\0\0\0 \0\0\0\0\0\2\0\0\0\6\0\0\0`\353\33\0\0\0\0\0`\353;\0\0\0\0\0`\353;\0\0\0\0\0\360\1\0\0\0\0\0\0\360\1\0\0\0\0\0\0\10\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\340\2\0\0\0\0\0\0\340\2\0\0\0\0\0\0\340\2\0\0\0\0\0\0D\0\0\0\0\0\0\0D\0\0\0\0\0\0\0\4\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0(\3\0\0\0\0\0\0(\3\0\0\0\0\0\0(\3\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0\7\0\0\0\4\0\0\0p\267\33\0\0\0\0\0p\267;\0\0\0\0\0p\267;\0\0\0\0\0\20\0\0\0\0\0\0\0\220\0\0\0\0\0\0\0\10\0\0\0\0\0\0\0S\345td\4\0\0\0(\3\0\0\0\0\0\0(\3\0\0\0\0\0\0(\3\0\0\0\0\0\0 \0\0\0\0\0\0\0 \0\0\0\0\0\0\0\10\0\0\0\0\0\0\0P\345td\4\0\0\0\254\v\31\0\0\0\0\0\254\v\31\0\0\0\0\0\254\v\31\0\0\0\0\0tb\0\0\0\0\0\0tb\0\0\0\0\0\0\4\0\0\0\0\0\0\0Q\345td\6\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0R\345td\4\0\0\0p\267\33\0\0\0\0\0p\267;\0\0\0\0\0p\267;\0\0\0\0\0\2208\0\0\0\0\0\0\2208\0\0\0\0\0\0\1\0\0\0\0\0\0\0\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\374.S\t\4f\365;]\370\3612\377\325\7\345\32\377\374w\4\0\0\0\20\0\0\0\1\0\0\0GNU\0\0\0\0\0\3\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0", 832) = 832
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0755, st_size=3168632, ...}) = 0
3205  23:51:38 lseek(3, 808, SEEK_SET)  = 808
3205  23:51:38 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
3205  23:51:38 mmap(NULL, 3950400, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd59181d000
3205  23:51:38 mprotect(0x7fd5919d9000, 2093056, PROT_NONE) = 0
3205  23:51:38 mmap(0x7fd591bd8000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1bb000) = 0x7fd591bd8000
3205  23:51:38 mmap(0x7fd591bde000, 14144, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fd591bde000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fd592420000
3205  23:51:38 arch_prctl(ARCH_SET_FS, 0x7fd592420b80) = 0
3205  23:51:38 mprotect(0x7fd591bd8000, 16384, PROT_READ) = 0
3205  23:51:38 mprotect(0x7fd591ded000, 4096, PROT_READ) = 0
3205  23:51:38 mprotect(0x7fd5921fc000, 4096, PROT_READ) = 0
3205  23:51:38 mprotect(0x7fd591ff6000, 4096, PROT_READ) = 0
3205  23:51:38 mprotect(0x5635a1989000, 8192, PROT_READ) = 0
3205  23:51:38 mprotect(0x7fd59242a000, 4096, PROT_READ) = 0
3205  23:51:38 munmap(0x7fd592424000, 21163) = 0
3205  23:51:38 rt_sigaction(SIGUSR1, {sa_handler=0x5635a173a100, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGUSR2, {sa_handler=0x5635a173ae20, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGCHLD, {sa_handler=0x5635a173a060, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 geteuid()                = 0
3205  23:51:38 getegid()                = 0
3205  23:51:38 umask(000)               = 022
3205  23:51:38 umask(022)               = 000
3205  23:51:38 brk(NULL)                = 0x5635a1c8c000
3205  23:51:38 brk(0x5635a1cad000)      = 0x5635a1cad000
3205  23:51:38 brk(NULL)                = 0x5635a1cad000
3205  23:51:38 openat(AT_FDCWD, "/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
3205  23:51:38 fstat(3, {st_mode=S_IFREG|0644, st_size=217796128, ...}) = 0
3205  23:51:38 mmap(NULL, 217796128, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd584868000
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/etc/popt", O_RDONLY) = -1 ENOENT (No such file or directory)
3205  23:51:38 stat("/etc/popt.d", {st_mode=S_IFDIR|0755, st_size=6, ...}) = 0
3205  23:51:38 openat(AT_FDCWD, "/etc/popt.d", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3
3205  23:51:38 fstat(3, {st_mode=S_IFDIR|0755, st_size=6, ...}) = 0
3205  23:51:38 getdents64(3, 0x5635a1c8d750 /* 2 entries */, 32768) = 48
3205  23:51:38 openat(AT_FDCWD, "/usr/lib64/gconv/gconv-modules.cache", O_RDONLY) = 4
3205  23:51:38 fstat(4, {st_mode=S_IFREG|0644, st_size=26998, ...}) = 0
3205  23:51:38 mmap(NULL, 26998, PROT_READ, MAP_SHARED, 4, 0) = 0x7fd592419000
3205  23:51:38 close(4)                 = 0
3205  23:51:38 getdents64(3, 0x5635a1c8d750 /* 0 entries */, 32768) = 0
3205  23:51:38 close(3)                 = 0
3205  23:51:38 openat(AT_FDCWD, "/root/.popt", O_RDONLY) = -1 ENOENT (No such file or directory)
3205  23:51:38 rt_sigaction(SIGINT, {sa_handler=0x5635a1728c90, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGHUP, {sa_handler=0x5635a1728c90, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGTERM, {sa_handler=0x5635a1728c90, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigprocmask(SIG_UNBLOCK, [HUP INT USR1 USR2 TERM CHLD], NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGPIPE, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGXFSZ, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER|SA_NOCLDSTOP, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 getcwd("/root", 4095)    = 6
3205  23:51:38 fcntl(0, F_GETFL)        = 0 (flags O_RDONLY)
3205  23:51:38 fcntl(0, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
3205  23:51:38 fcntl(1, F_GETFL)        = 0x1 (flags O_WRONLY)
3205  23:51:38 fcntl(1, F_SETFL, O_WRONLY|O_NONBLOCK) = 0
3205  23:51:38 fcntl(0, F_GETFL)        = 0x800 (flags O_RDONLY|O_NONBLOCK)
3205  23:51:38 fcntl(1, F_GETFL)        = 0x801 (flags O_WRONLY|O_NONBLOCK)
3205  23:51:38 write(1, "\37\0\0\0", 4) = 4
3205  23:51:38 select(1, [0], NULL, [0], {tv_sec=60, tv_usec=0}) = 1 (in [0], left {tv_sec=59, tv_usec=999998})
3205  23:51:38 read(0, "", 4)           = 0
3205  23:51:38 write(2, "rsync: connection unexpectedly closed (0 bytes received so far) [sender]", 72) = 72
3205  23:51:38 write(2, "\n", 1)        = 1
3205  23:51:38 rt_sigaction(SIGUSR1, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 rt_sigaction(SIGUSR2, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd591854400}, NULL, 8) = 0
3205  23:51:38 write(2, "rsync error: error in rsync protocol data stream (code 12) at io.c(226) [sender=3.1.3]", 86) = 86
3205  23:51:38 write(2, "\n", 1)        = 1
3205  23:51:38 nanosleep({tv_sec=0, tv_nsec=100000000}, NULL) = 0
3205  23:51:38 exit_group(12)           = ?
3205  23:51:38 +++ exited with 12 +++

Re: rsync gives me error 12

[Adam Dinwoodie]

On Fri, Mar 25, 2022 at 12:36:55PM -0500, Neil Aggarwal wrote:
> Adam:
> 
> I tried doing an upload using this command:
> rsync test.xml root@marketing.propfinancing.com:/tmp
> 
> I still get an error:
> rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
> rsync error: error in rsync protocol data stream (code 12) at io.c(226)
> [Receiver=3.1.3]
> rsync: connection unexpectedly closed (0 bytes received so far) [sender]
> rsync error: error in rsync protocol data stream (code 12) at io.c(228)
> [sender=3.2.4dev]
> 
> Could it be the rsync versions are somehow incompatible?

That seems unlikely: this is an incredibly generic error, and there are
*many* possible causes for it.  I've never heard of version
incompatibilities causing this sort of behaviour, and if they did, I'd
expect that would be very widely documented.  I'd expect the versions
would need to differ much more substantially before that would cause an
issue.

At this point, I don't think there's any evidence there's a problem with
Cygwin itself, or with the rsync packaging for Cygwin; this looks like a
problem with either your server setup or your rsync configuration.  As
such -- at least unless I've missed something that points to it being a
problem with Cygwin or the Cygwin rsync package in particular -- I think
we're rapidly veering off topic for the Cygwin mailing list.

> Would it help if I create a login for you on the server so
> you can try doing an rsync yourself?

If you can provide steps by which someone could set up their own
ssh+rsync server and reproduce this problem, you might be able to get
further help on some other open source forum.  If those steps
necessarily require Cygwin, you might even be able to get further help
on this mailing list.

I'm afraid, however, that connecting to your server to help diagnose
problems there is the sort of thing you probably need to pay someone
to do.

RE: rsync gives me error 12

[Neil Aggarwal]

Adam:

Thanks for the opinion on the version differences.

Regarding my server setup, this command works:
scp root@marketing.propfinancing.com:/var/www/svnDumps/*  "c:\\Tmp"

So I am not completely convinced the problem lies in the network or my
server.

It would be nice to get rsync working since it will be a more efficient
solution.
But, I am not going to spend a lot of effort on it.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

Re: rsync gives me error 12

[Wayne Davison]

On Fri, Mar 25, 2022 at 10:01 PM wrote:
> On the server, I got a /tmp/rsync-3191.out file which I am attaching here.
> I can't make sense of what it is trying to tell me.

It shows you the system calls being made. If you read down to the end,
rsync writes 4 bytes and then tries to read 4 bytes from the remote
side. At that point it gets a closed socket error.

Thus, the issue isn't rsync but that the connection is closing without
either side being able to send/receive any data. (Both sides are
seeing the connection go away.)  Perhaps the networking drivers are
buggy.  Or perhaps you have firewall or selinux rules that are closing
the connection.  Your /var/log files might have a clue.

..wayne..

RE: rsync gives me error 12

[Neil Aggarwal]

Wayne:

> Perhaps the networking drivers are buggy

This is an AlmaLinux 8 server, which is a binary clone of RHEL.
It is possible but hard to believe the networking drivers would
be a problem.

> Perhaps you have firewall  or selinux rules that are closing the
> connection.

I configured the firewall using nftables.  It only allows incoming
tcp connections to port 22 and drops everything else.  For outbound, it
is set to drop all connections with specific ports allowed.

I have selinux set to:
SELINUX=permissive
SELINUXTYPE=targeted

> Your /var/log files might have a clue.

I looked at /var/log/messages, it has this:
Mar 26 23:38:44 marketing systemd[1]: Started Session 3 of user root.
Mar 26 23:38:44 marketing systemd-logind[849]: New session 3 of user root.
Mar 26 23:38:44 marketing systemd[1]: session-3.scope: Succeeded.
Mar 26 23:38:44 marketing systemd-logind[849]: Session 3 logged out. Waiting
for processes to exit.
Mar 26 23:38:44 marketing systemd-logind[849]: Removed session 3.

I did not see anything else which seemed related.

The big stumper for me is that scp works.  What could cause rsync to act
differently?
They are both connecting through port 22.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

Re: rsync gives me error 12

[gs-cygwin.com]

On Sat, Mar 26, 2022 at 11:49:18PM -0500, Neil Aggarwal wrote:
> The big stumper for me is that scp works.  What could cause rsync to act
> differently?

Likely answer: your server configuration, such as ssh config,
shell configuration, PATH, etc. which are a bit off-topic for
the cygwin list.

Manually ssh to the server and try to run `rsync -h`.

$ ssh -vvv user@server rsync -h

You can also get verbose ssh information from rsync using

$ rsync -e 'ssh -vvv' ...

You can also turn on verbose sshd logging
$ man sshd_config
See LogLevel and LogVerbose

RE: rsync gives me error 12

[Neil Aggarwal]

[-- Attachment #1: Type: text/plain, Size: 1394 bytes --]

> Manually ssh to the server and try to run `rsync -h`.

I ran this command:
ssh -vvv root@marketing.propfinancing.com rsync -h

It seemed to work. It gave me the output from rsync-h

> You can also get verbose ssh information from rsync using

I ran this command:
rsync -e 'ssh -vvv' root@marketing.propfinancing.com:/var/www/svnDumps/*
"/cygdrive/c/Tmp"

It seems to have a problem at this point:
debug1: Sending command: rsync --server --sender -e.LsfxCIvu .
/var/www/svnDumps/*
debug2: channel 0: request exec confirm 1
debug3: send packet: type 98
debug2: channel_input_open_confirmation: channel 0: callback done
debug2: channel 0: open confirm rwindow 0 rmax 32768
debug2: channel 0: rcvd adjust 2097152
debug3: receive packet: type 99
debug2: channel_input_status_confirm: type 99 id 0
debug2: exec request accepted on channel 0
debug3: read - ERROR from cb :87, io:0000027479707820
debug2: channel 0: read<=0 rfd 4 len 4294967295
debug2: channel 0: read failed

> You can also turn on verbose sshd logging

I set the LogLevel to DEBUG3 on the server.
When I tried the rsync command on my Windows machine, I get a bunch of
logs in
/var/log/secure.  I think the relevant part is attached.

It seems to say the channel is closed, but not why it is closing.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

[-- Attachment #2: secureLog.txt --]
[-- Type: text/plain, Size: 5342 bytes --]

Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_audit_run_command entering command rsync --server --sender -e.LsfxCIvu . /var/www/svnDumps/*
Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_request_send entering: type 114
Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_request_receive_expect entering: type 115
Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_request_receive entering
Mar 27 09:24:14 marketing sshd[3254]: debug3: mm_request_receive entering
Mar 27 09:24:14 marketing sshd[3254]: debug3: monitor_read: checking request 114
Mar 27 09:24:14 marketing sshd[3254]: debug3: mm_answer_audit_command entering
Mar 27 09:24:14 marketing sshd[3254]: debug2: session_new: allocate (allocated 0 max 10)
Mar 27 09:24:14 marketing sshd[3254]: debug3: session_unused: session id 0 unused
Mar 27 09:24:14 marketing sshd[3254]: debug1: session_new: session 0
Mar 27 09:24:14 marketing sshd[3254]: debug3: mm_request_send entering: type 115
Mar 27 09:24:14 marketing sshd[3257]: debug2: fd 5 setting TCP_NODELAY
Mar 27 09:24:14 marketing sshd[3257]: debug3: ssh_packet_set_tos: set IP_TOS 0x20
Mar 27 09:24:14 marketing sshd[3257]: debug2: fd 17 setting O_NONBLOCK
Mar 27 09:24:14 marketing sshd[3257]: debug2: fd 16 setting O_NONBLOCK
Mar 27 09:24:14 marketing sshd[3257]: debug2: fd 19 setting O_NONBLOCK
Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_forward_audit_messages: entering
Mar 27 09:24:14 marketing sshd[3258]: debug3: mm_request_send entering: type 124
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: SELINUX_ROLE_REQUESTED=
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: SELINUX_LEVEL_REQUESTED=
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: SELINUX_USE_CURRENT_RANGE=
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: XDG_SESSION_ID=24
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0
Mar 27 09:24:14 marketing sshd[3258]: debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus
Mar 27 09:24:14 marketing sshd[3257]: debug3: send packet: type 99
Mar 27 09:24:14 marketing sshd[3254]: debug3: mm_request_receive entering
Mar 27 09:24:14 marketing sshd[3254]: debug3: monitor_read: checking request 124
Mar 27 09:24:14 marketing sshd[3257]: debug3: receive packet: type 96
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: rcvd eof
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: output open -> drain
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: obuf empty
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: chan_shutdown_write (i0 o1 sock -1 wfd 16 efd 19 [read])
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: output drain -> closed
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: read 160 from efd 19
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: rwin 2097148 elen 160 euse 1
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: sent ext data 160
Mar 27 09:24:14 marketing sshd[3257]: debug1: Received SIGCHLD.
Mar 27 09:24:14 marketing sshd[3257]: debug1: session_by_pid: pid 3258
Mar 27 09:24:14 marketing sshd[3257]: debug1: session_exit_message: session 0 channel 0 pid 3258
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: request exit-status confirm 0
Mar 27 09:24:14 marketing sshd[3257]: debug3: send packet: type 98
Mar 27 09:24:14 marketing sshd[3257]: debug1: session_exit_message: release channel 0
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: read<=0 rfd 17 len 0
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: read failed
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: chan_shutdown_read (i0 o3 sock -1 wfd 17 efd 19 [read])
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: input open -> drain
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: read 0 from efd 19
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: closing read-efd 19
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: ibuf empty
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: send eof
Mar 27 09:24:14 marketing sshd[3257]: debug3: send packet: type 96
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: input drain -> closed
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: send close
Mar 27 09:24:14 marketing sshd[3257]: debug3: send packet: type 97
Mar 27 09:24:14 marketing sshd[3257]: debug2: notify_done: reading
Mar 27 09:24:14 marketing sshd[3257]: debug3: channel 0: will not send data after close
Mar 27 09:24:14 marketing sshd[3257]: debug3: receive packet: type 97
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: rcvd close
Mar 27 09:24:14 marketing sshd[3257]: debug3: channel 0: will not send data after close
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: is dead
Mar 27 09:24:14 marketing sshd[3257]: debug2: channel 0: gc: notify user
Mar 27 09:24:14 marketing sshd[3257]: debug1: session_by_channel: session 0 channel 0
Mar 27 09:24:14 marketing sshd[3257]: debug1: session_close_by_channel: channel 0 child 0
Mar 27 09:24:14 marketing sshd[3257]: Close session: user root from 99.137.191.183 port 65303 id 0
Mar 27 09:24:14 marketing sshd[3257]: debug3: mm_audit_end_command entering command rsync --server --sender -e.LsfxCIvu . /var/www/svnDumps/*

Re: rsync gives me error 12

[Andrey Repin]

Greetings, Neil Aggarwal!

> Adam:

>> this reads to me like the remote
>> server is closing the connection

> When I run this command on my Windows machine:
> rsync --debug=ALL root@marketing.propfinancing.com:/var/www/svnDumps
> /cygdrive/c/Tmp

> I get this output on the screen:
> opening connection using: ssh -l root marketing.propfinancing.com rsync
> --server --sender -e.LsfxCIvu . /var/www/svnDumps  (10 args)
> rsync: connection unexpectedly closed (0 bytes received so far) [sender]
> rsync error: error in rsync protocol data stream (code 12) at io.c(226)
> [sender=3.1.3]
> rsync: connection unexpectedly closed (0 bytes received so far) [Receiver]
> rsync error: error in rsync protocol data stream (code 12) at io.c(228)
> [Receiver=3.2.4dev]
> [Receiver] _exit_cleanup(code=12, file=io.c, line=228): about to call
> exit(12)

Please pause here for a moment. Are you using Cygwin ssh or MS Windows provided 
variant? Cygwin rsync is unable to use native Windows apps as tunnel wrappers.
Be it OpenSSH or PuTTY's plink. With exactly the message you see above.

> It is connecting to the Linux server and immediately disconnecting, here
> is what I see in /var/log/secure:
> Mar 25 12:11:00 marketing sshd[43597]: Postponed publickey for root from
> 99.137.191.183 port 64715 ssh2 [preauth]
> Mar 25 12:11:00 marketing sshd[43597]: Accepted publickey for root from
> 99.137.191.183 port 64715 ssh2: RSA SHA256:[removed]
> Mar 25 12:11:00 marketing sshd[43597]: pam_unix(sshd:session): session
> opened for user root by (uid=0)
> Mar 25 12:11:00 marketing sshd[43600]: Received disconnect from
> 99.137.191.183 port 64715:11: disconnected by user
> Mar 25 12:11:00 marketing sshd[43600]: Disconnected from user root
> 99.137.191.183 port 64715
> Mar 25 12:11:00 marketing sshd[43597]: pam_unix(sshd:session): session
> closed for user root

> I'm stumped at this point.

> Thank you,
>   Neil

> --
> Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
> We offer 30 year loans on single family houses!



-- 
With best regards,
Andrey Repin
Monday, March 28, 2022 16:24:44

Sorry for my terrible english...

RE: rsync gives me error 12

[Neil Aggarwal]

> Please pause here for a moment. Are you using Cygwin ssh or MS Windows
provided
> variant? Cygwin rsync is unable to use native Windows apps as tunnel
wrappers.
> Be it OpenSSH or PuTTY's plink. With exactly the message you see above.

I think we may (finally!) be getting somewhere now.
I am using the Cygwin provided one.

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!

RE: rsync gives me error 12

[Neil Aggarwal]

> Please pause here for a moment. Are you using Cygwin ssh or MS Windows
provided
> variant? Cygwin rsync is unable to use native Windows apps as tunnel
wrappers.
> Be it OpenSSH or PuTTY's plink. With exactly the message you see above.

This was the problem.

I installed OpenSSH from Cygwin, copied my keys to my .ssh directory, and
then issued this command:
rsync root@marketing.propfinancing.com:/var/www/svnDumps/*
"/cygdrive/c/Tmp"

It worked perfectly.

Thank you Andrey!

Thank you,
  Neil

--
Neil Aggarwal, (972) 834-1565, http://www.propfinancing.com
We offer 30 year loans on single family houses!