rsync hanging on Cygwin

Jim Kleckner jek-rsync1 at kleckner.net
Thu May 27 00:04:52 GMT 2004


I also have a reproducible test case of rsync hanging when
fetching files remotely using ssh as the transport.  I have
strace output from both ends (near and far) with verbose rsync
output interspersed.  I have attached files with the tail end of
the near and far strace output.  If anyone would like, send me a
private email to arrange access to the full strace output.

Can any of you experts see something in these traces to suggest
what is causing the hang?	

Thanks - Jim

Setup:
   - "near end" is Windows XP SP2 running latest cygwin 1.5.10-3
   - "far end"  is Windows XP SP2 running cygwin 1.5.9
   - "near end" uses a command like this to pull the backup:
       rsync -v -v -v --stats -rultz -e ssh --delete far::path .
   - rsync was built from sources as of 2004-05-19 from "unpacked".

Variations tried:
   - Using either --blocking-io/--no-blocking-io on both ends
     doesn't solve the problem
   - Using MacOSX 10.2 as the "near end" (FreeBSD) doesn't solve
     the problem
   - Using RH Linux 7.2+progeny as the "near end" appears it may
     work (it hasn't stopped pulling files yet...)

The script at the near end to pull the backup with strace is:
   #!/bin/bash
   strace --trace-children --mask=all+flush rsync.exe -v -v -v --stats 
-rultz -e ssh --rsync-path=/tmp/dorsync --no-blocking-io --delete 
far:'/cygdrive/c/Documents\ and\ Settings/User\ Name/Desktop' . >& near.txt

The script at the far end (/tmp/dorsync) to cause strace to be
performed is:
   #!/bin/bash
   strace --trace-children --mask=all+flush --output=/tmp/strace.out 
rsync.exe --no-blocking-io "${@}"



Tim Moss wrote:
> The server is behind a firewall, so unless I can tunnel the rsync traffic
> through ssh then can't user the daemon option at the moment.
> 
> The manual says I can get the client to start the daemon on the server over
> ssh, but I don't know if the data then comes over ssh or if the client tries
> to then connect to thet standard daemon ports.
> 
> Anyone know?
> 
> What would the command line for doing this look like - I've tried a few
> options but if I put --daemon on the command line ven though I've got --rsh
> it just quits immediately.
> 
> 
> Tim
> 
> PS. From the tests I've run so far the rsync client doesn't hang when files
> are being "sent", only when it is receiving files.
> 
> 
> 
>>-----Original Message-----
>>From: Tevfik Karagulle [mailto:tevfik at itefix.no] 
>>Sent: 15 May 2004 10:49
>>To: tim at bango.net; rsync at lists.samba.org
>>Subject: SV: rsync hanging on Cygwin
>>
>>Hi,
>>
>>Do you get the same error when you use rsync daemon at server 
>>side (double colon syntax and without ssh) ?
>>
>>Rgrds Tev
[snip]
-------------- next part --------------
**********************************************
Program name: C:\cygwin\bin\rsync.exe (4028)
App version:  1005.9, api: 0.112
DLL version:  1005.10, api: 0.116
DLL build:    2004-05-25 22:07
OS version:   Windows NT-5.1
Heap size:    402653184
Date/Time:    2004-05-26 14:23:56
**********************************************

[snip]

recv_generator(Desktop/terri babies/gramma!21A.jpg,1116)
    
   68 37533247 [main] rsync 4028 fhandler_base::write: 57 = write (0x100120E8, 57)
   57 37533304 [main] rsync 4028 writev: 57 = write (1, 0x22E6B0, 1), errno 2
  119 37533423 [main] rsync 4028 lstat64: entering
   62 37533485 [main] rsync 4028 normalize_posix_path: src Desktop/terri babies/gramma!21A.jpg
   60 37533545 [main] rsync 4028 cwdstuff::get: posix /tmp/xx
  120 37533665 [main] rsync 4028 cwdstuff::get: (/tmp/xx) = cwdstuff::get (0x22E790, 260, 1, 0), errno 2
   58 37533723 [main] rsync 4028 normalize_posix_path: /tmp/xx/Desktop/terri babies/gramma!21A.jpg = normalize_posix_path (Desktop/terri babies/gramma!21A.jpg)
  113 37533836 [main] rsync 4028 mount_info::conv_to_win32_path: conv_to_win32_path (/tmp/xx/Desktop/terri babies/gramma!21A.jpg)
   65 37533901 [main] rsync 4028 set_flags: flags: binary (0x2)
   56 37533957 [main] rsync 4028 mount_info::conv_to_win32_path: src_path /tmp/xx/Desktop/terri babies/gramma!21A.jpg, dst C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg, flags 0xA, rc 0
  458 37534415 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg) failed
   91 37534506 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  222 37534728 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg.exe) failed
   70 37534798 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  276 37535074 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg.exe.lnk) failed
   76 37535150 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  206 37535356 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg.lnk) failed
   73 37535429 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
   56 37535485 [main] rsync 4028 symlink_info::check: 0 = symlink.check (C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg, 0x22E450) (0xA)
   60 37535545 [main] rsync 4028 mount_info::conv_to_win32_path: conv_to_win32_path (/tmp/xx/Desktop/terri babies)
   72 37535617 [main] rsync 4028 set_flags: flags: binary (0x2)
   82 37535699 [main] rsync 4028 mount_info::conv_to_win32_path: src_path /tmp/xx/Desktop/terri babies, dst C:\cygwin\tmp\xx\Desktop\terri babies, flags 0xA, rc 0
  294 37535993 [main] rsync 4028 symlink_info::check: not a symlink
   68 37536061 [main] rsync 4028 symlink_info::check: 0 = symlink.check (C:\cygwin\tmp\xx\Desktop\terri babies, 0x22E450) (0xA)
   63 37536124 [main] rsync 4028 path_conv::check: this->path(C:\cygwin\tmp\xx\Desktop\terri babies\gramma!21A.jpg), has_acls(1)
   67 37536191 [main] rsync 4028 build_fh_pc: fh 0x616D1C88
   65 37536256 [main] rsync 4028 stat_worker: -1 = (Desktop/terri babies/gramma!21A.jpg, 0x22ECE0)
  102 37536358 [main] rsync 4028 writev: writev (1, 0x22E6B0, 1)
   60 37536418 [main] rsync 4028 fhandler_base::write: binary write

recv_generator(Desktop/terri babies/xmasbabes8-24email.jpg,1117)
    
  295 37536713 [main] rsync 4028 fhandler_base::write: 65 = write (0x100120E8, 65)
   72 37536785 [main] rsync 4028 writev: 65 = write (1, 0x22E6B0, 1), errno 2
   66 37536851 [main] rsync 4028 lstat64: entering
  124 37536975 [main] rsync 4028 normalize_posix_path: src Desktop/terri babies/xmasbabes8-24email.jpg
  248 37537223 [main] rsync 4028 cwdstuff::get: posix /tmp/xx
   61 37537284 [main] rsync 4028 cwdstuff::get: (/tmp/xx) = cwdstuff::get (0x22E790, 260, 1, 0), errno 2
   58 37537342 [main] rsync 4028 normalize_posix_path: /tmp/xx/Desktop/terri babies/xmasbabes8-24email.jpg = normalize_posix_path (Desktop/terri babies/xmasbabes8-24email.jpg)
   60 37537402 [main] rsync 4028 mount_info::conv_to_win32_path: conv_to_win32_path (/tmp/xx/Desktop/terri babies/xmasbabes8-24email.jpg)
   63 37537465 [main] rsync 4028 set_flags: flags: binary (0x2)
  206 37537671 [main] rsync 4028 mount_info::conv_to_win32_path: src_path /tmp/xx/Desktop/terri babies/xmasbabes8-24email.jpg, dst C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg, flags 0xA, rc 0
  333 37538004 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg) failed
   80 37538084 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  291 37538375 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg.exe) failed
   78 37538453 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  225 37538678 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg.exe.lnk) failed
   76 37538754 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
  201 37538955 [main] rsync 4028 symlink_info::check: GetFileAttributes (C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg.lnk) failed
   68 37539023 [main] rsync 4028 geterrno_from_win_error: windows error 2 == errno 2
   56 37539079 [main] rsync 4028 symlink_info::check: 0 = symlink.check (C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg, 0x22E450) (0xA)
   61 37539140 [main] rsync 4028 mount_info::conv_to_win32_path: conv_to_win32_path (/tmp/xx/Desktop/terri babies)
   60 37539200 [main] rsync 4028 set_flags: flags: binary (0x2)
   60 37539260 [main] rsync 4028 mount_info::conv_to_win32_path: src_path /tmp/xx/Desktop/terri babies, dst C:\cygwin\tmp\xx\Desktop\terri babies, flags 0xA, rc 0
  194 37539454 [main] rsync 4028 symlink_info::check: not a symlink
   63 37539517 [main] rsync 4028 symlink_info::check: 0 = symlink.check (C:\cygwin\tmp\xx\Desktop\terri babies, 0x22E450) (0xA)
   69 37539586 [main] rsync 4028 path_conv::check: this->path(C:\cygwin\tmp\xx\Desktop\terri babies\xmasbabes8-24email.jpg), has_acls(1)
   68 37539654 [main] rsync 4028 build_fh_pc: fh 0x616D1C88
   60 37539714 [main] rsync 4028 stat_worker: -1 = (Desktop/terri babies/xmasbabes8-24email.jpg, 0x22ECE0)
  102 37539816 [main] rsync 4028 writev: writev (1, 0x22E980, 1)
   58 37539874 [main] rsync 4028 fhandler_base::write: binary write

generate_files phase=1
    
   64 37539938 [main] rsync 4028 fhandler_base::write: 23 = write (0x100120E8, 23)
  618 37540556 [main] rsync 4028 writev: 23 = write (1, 0x22E980, 1), errno 2
   90 37540646 [main] rsync 4028 cygwin_select: 5, 0x0, 0x22EB70, 0x0, 0x22EB60
  111 37540757 [main] rsync 4028 dtable::select_write:  fd 4
   55 37540812 [main] rsync 4028 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
   55 37540867 [main] rsync 4028 cygwin_select: sel.always_ready 0
  128 37540995 [main] rsync 4028 start_thread_socket: Handle 0x6D0
   55 37541050 [main] rsync 4028 start_thread_socket: Added to writefds
  422 37541472 [main] rsync 4028 start_thread_socket: exitsock 0x6AC
   80 37541552 [main] rsync 4028 start_thread_socket: stuff_start 0x22EAD4
  150 37541702 [main] rsync 4028 select_stuff::wait: m 2, ms 60000
   73 37541775 [select_socket] rsync 4028 thread_socket: stuff_start 0x100776D4
   92 37541867 [select_socket] rsync 4028 thread_socket: Win32 select returned 1
   58 37541925 [select_socket] rsync 4028 thread_socket: s 0x10010170, testing fd 4 ()
   56 37541981 [select_socket] rsync 4028 thread_socket: write_ready
   65 37542046 [main] rsync 4028 select_stuff::wait: woke up.  wait_ret 1.  verifying
   57 37542103 [main] rsync 4028 select_stuff::wait: gotone 1
   54 37542157 [main] rsync 4028 select_stuff::wait: returning 0
  154 37542311 [main] rsync 4028 select_stuff::cleanup: calling cleanup routines
   56 37542367 [main] rsync 4028 socket_cleanup: si 0x100746B0 si->thread 0x610F78D8
   56 37542423 [main] rsync 4028 socket_cleanup: connection to si->exitsock 0x6AC
  913 37543336 [main] rsync 4028 socket_cleanup: returning
   85 37543421 [main] rsync 4028 peek_socket: considering handle 0x6D0
   60 37543481 [main] rsync 4028 set_bits: me 0x10010170, testing fd 4 ()
   59 37543540 [main] rsync 4028 set_bits: ready 1
   64 37543604 [main] rsync 4028 select_stuff::poll: returning 1
   54 37543658 [main] rsync 4028 select_stuff::cleanup: calling cleanup routines
   55 37543713 [main] rsync 4028 select_stuff::~select_stuff: deleting select records
  102 37543815 [main] rsync 4028 writev: writev (4, 0x22EB20, 1)
 1809 37545624 [main] rsync 4028 writev: 804 = write (4, 0x22EB20, 1), errno 0
  165 37545789 [main] rsync 4028 cygwin_select: 4, 0x22EBF0, 0x0, 0x0, 0x22EBE0
  105 37545894 [main] rsync 4028 dtable::select_read:  fd 3
   55 37545949 [main] rsync 4028 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
   55 37546004 [main] rsync 4028 cygwin_select: sel.always_ready 0
 1386 37547390 [main] rsync 4028 start_thread_socket: Handle 0x66C
   92 37547482 [main] rsync 4028 start_thread_socket: Added to readfds
  339 37547821 [main] rsync 4028 start_thread_socket: exitsock 0x6AC
  210 37548031 [main] rsync 4028 start_thread_socket: stuff_start 0x22EB54
  144 37548175 [main] rsync 4028 select_stuff::wait: m 2, ms 60000
   70 37548245 [select_socket] rsync 4028 thread_socket: stuff_start 0x100776D4

[This is where the hang occurs]
    
59999787 97548032 [main] rsync 4028 select_stuff::wait: timed out
  170 97548202 [main] rsync 4028 select_stuff::wait: returning 1
   57 97548259 [main] rsync 4028 select_stuff::cleanup: calling cleanup routines
   56 97548315 [main] rsync 4028 socket_cleanup: si 0x100746B0 si->thread 0x610F78D8
   56 97548371 [main] rsync 4028 socket_cleanup: connection to si->exitsock 0x6AC
  689 97549060 [select_socket] rsync 4028 thread_socket: Win32 select returned 1
  115 97549175 [select_socket] rsync 4028 thread_socket: s 0x10010170, testing fd 3 ()
   57 97549232 [select_socket] rsync 4028 thread_socket: saw exitsock read
  352 97549584 [main] rsync 4028 socket_cleanup: returning
   66 97549650 [main] rsync 4028 peek_socket: considering handle 0x66C
   58 97549708 [main] rsync 4028 peek_socket: adding read fd_set , fd 3
   80 97549788 [main] rsync 4028 peek_socket: WINSOCK_SELECT returned 0
   57 97549845 [main] rsync 4028 select_stuff::poll: returning 0
   54 97549899 [main] rsync 4028 select_stuff::cleanup: calling cleanup routines
   58 97549957 [main] rsync 4028 select_stuff::~select_stuff: deleting select records
  139 97550096 [main] rsync 4028 cygwin_select: 4, 0x22EBF0, 0x0, 0x0, 0x22EBE0
  129 97550225 [main] rsync 4028 dtable::select_read:  fd 3
   55 97550280 [main] rsync 4028 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
   59 97550339 [main] rsync 4028 cygwin_select: sel.always_ready 0
  125 97550464 [main] rsync 4028 start_thread_socket: Handle 0x66C
   55 97550519 [main] rsync 4028 start_thread_socket: Added to readfds
  351 97550870 [main] rsync 4028 start_thread_socket: exitsock 0x6AC
   76 97550946 [main] rsync 4028 start_thread_socket: stuff_start 0x22EB54
  150 97551096 [main] rsync 4028 select_stuff::wait: m 2, ms 60000
   68 97551164 [select_socket] rsync 4028 thread_socket: stuff_start 0x100776D4
-------------- next part --------------
**********************************************
Program name: C:\cygwin\bin\rsync.exe (9964)
App version:  1005.9, api: 0.112
DLL version:  1005.9, api: 0.112
DLL build:    2004-03-18 23:05
OS version:   Windows NT-5.1
Heap size:    402653184
Date/Time:    2004-05-26 14:24:18
**********************************************

[snip]

  268 31447966 [main] rsync 9964 open: open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk, 0x10000)
  159 31448125 [main] rsync 9964 normalize_posix_path: src /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk
  156 31448281 [main] rsync 9964 normalize_posix_path: /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk = normalize_posix_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk)
  162 31448443 [main] rsync 9964 mount_info::conv_to_win32_path: conv_to_win32_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk)
  164 31448607 [main] rsync 9964 mount_info::cygdrive_win32_path: src '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk', dst 'c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk'
  158 31448765 [main] rsync 9964 set_flags: flags: binary (0x2)
  141 31448906 [main] rsync 9964 mount_info::conv_to_win32_path: src_path /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk, dst c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, flags 0x22, rc 0
 3844 31452750 [main] rsync 9964 get_symlink_ea: Cannot read symlink from EA
 3514 31456264 [main] rsync 9964 symlink_info::check: not a symlink
  198 31456462 [main] rsync 9964 symlink_info::check: 0 = symlink.check (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 0x226500) (0x22)
  157 31456619 [main] rsync 9964 path_conv::check: root_dir(c:\), this->path(c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk), set_has_acls(8)
  181 31456800 [main] rsync 9964 build_fh_pc: fh 0x616E23D4
  147 31456947 [main] rsync 9964 fhandler_base::open: (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 0x110000) query_open 0
  834 31457781 [main] rsync 9964 fhandler_base::open: 0x6B4 = CreateFile (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 0x80000000, 0x7, 0x226C80, 0x3, 0x2000081, 0)
  191 31457972 [main] rsync 9964 fhandler_base::set_flags: flags 0x110000, supplied_bin 0x10000
  145 31458117 [main] rsync 9964 fhandler_base::set_flags: O_TEXT/O_BINARY set in flags 0x10000
  141 31458258 [main] rsync 9964 fhandler_base::set_flags: filemode set to binary
  141 31458399 [main] rsync 9964 fhandler_base::open: 1 = fhandler_base::open (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 0x110000)
  151 31458550 [main] rsync 9964 fhandler_base::open_fs: 1 = fhandler_disk_file::open (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 0x10000)
  152 31458702 [main] rsync 9964 open: 3 = open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk, 0x10000)
  234 31458936 [main] rsync 9964 fhandler_base::fstat_by_handle: 1 = GetFileInformationByHandle (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk, 1716)
  825 31459761 [main] rsync 9964 get_file_attribute: file: c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk
 1929 31461690 [main] rsync 9964 read_sd: file = c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to adobe-pdf.lnk: len=164
  830 31462520 [main] rsync 9964 cygpsid::debug_print: get_sids_info: owner SID = S-1-5-21-73586283-854245398-1708537768-1003
  168 31462688 [main] rsync 9964 cygpsid::debug_print: get_sids_info: group SID = S-1-5-21-73586283-854245398-1708537768-513
  155 31462843 [main] rsync 9964 get_info_from_sd: ACL = 1C0, uid 1003, gid 513
  268 31463111 [main] rsync 9964 fhandler_base::fstat_helper: 0 = fstat (, 0x22EEE0) st_atime=40B50B04 st_size=275, st_mode=0x81C0, st_ino=25188, sizeof=96
  150 31463261 [main] rsync 9964 fstat64: 0 = fstat (3, 0x22EEE0)
  421 31463682 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  257 31463939 [main] rsync 9964 dtable::select_write:  fd 1
  145 31464084 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31464227 [main] rsync 9964 cygwin_select: sel.always_ready 1
  138 31464365 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
 1187 31465552 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  170 31465722 [main] rsync 9964 set_bits: ready 1
  140 31465862 [main] rsync 9964 select_stuff::poll: returning 1
  146 31466008 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  142 31466150 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  248 31466398 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  145 31466543 [main] rsync 9964 fhandler_base::write: binary write
  172 31466715 [main] rsync 9964 fhandler_base::write: 129 = write (0x225980, 129)
  148 31466863 [main] rsync 9964 writev: 129 = write (1, 0x225900, 1), errno 0
  178 31467041 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225930, 0x0, 0x225920
  260 31467301 [main] rsync 9964 dtable::select_write:  fd 1
  140 31467441 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31467584 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31467725 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31467869 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  149 31468018 [main] rsync 9964 set_bits: ready 1
  141 31468159 [main] rsync 9964 select_stuff::poll: returning 1
  140 31468299 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31468440 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  246 31468686 [main] rsync 9964 writev: writev (1, 0x2258E0, 1)
  143 31468829 [main] rsync 9964 fhandler_base::write: binary write
  161 31468990 [main] rsync 9964 fhandler_base::write: 24 = write (0x225960, 24)
  151 31469141 [main] rsync 9964 writev: 24 = write (1, 0x2258E0, 1), errno 0
  150 31469291 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  250 31469541 [main] rsync 9964 dtable::select_write:  fd 1
  140 31469681 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  144 31469825 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31469965 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  149 31470114 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31470257 [main] rsync 9964 set_bits: ready 1
  138 31470395 [main] rsync 9964 select_stuff::poll: returning 1
  140 31470535 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  145 31470680 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  243 31470923 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  290 31471213 [main] rsync 9964 fhandler_base::write: binary write
  168 31471381 [main] rsync 9964 fhandler_base::write: 118 = write (0x225980, 118)
  148 31471529 [main] rsync 9964 writev: 118 = write (1, 0x225900, 1), errno 0
  958 31472487 [main] rsync 9964 readv: readv (3, 0x226BC0, 1) blocking, sigcatchers 6
  186 31472673 [main] rsync 9964 readv: no need to call ready_for_read
  377 31473050 [main] rsync 9964 fhandler_base::read: returning 275, binary mode
  157 31473207 [main] rsync 9964 readv: 275 = readv (3, 0x226BC0, 1), errno 0
  456 31473663 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258D0, 0x0, 0x2258C0
  269 31473932 [main] rsync 9964 dtable::select_write:  fd 1
  143 31474075 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  141 31474216 [main] rsync 9964 cygwin_select: sel.always_ready 1
  138 31474354 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  142 31474496 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  140 31474636 [main] rsync 9964 set_bits: ready 1
  137 31474773 [main] rsync 9964 select_stuff::poll: returning 1
  136 31474909 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
 1941 31476850 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  281 31477131 [main] rsync 9964 writev: writev (1, 0x225880, 1)
  142 31477273 [main] rsync 9964 fhandler_base::write: binary write
  193 31477466 [main] rsync 9964 fhandler_base::write: 183 = write (0x225900, 183)
  148 31477614 [main] rsync 9964 writev: 183 = write (1, 0x225880, 1), errno 0
  150 31477764 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258F0, 0x0, 0x2258E0
 2676 31480440 [main] rsync 9964 dtable::select_write:  fd 1
  184 31480624 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  145 31480769 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31480910 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  300 31481210 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  151 31481361 [main] rsync 9964 set_bits: ready 1
  140 31481501 [main] rsync 9964 select_stuff::poll: returning 1
  139 31481640 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  142 31481782 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  248 31482030 [main] rsync 9964 writev: writev (1, 0x2258A0, 1)
  148 31482178 [main] rsync 9964 fhandler_base::write: binary write
  166 31482344 [main] rsync 9964 fhandler_base::write: 21 = write (0x225920, 21)
  147 31482491 [main] rsync 9964 writev: 21 = write (1, 0x2258A0, 1), errno 0
  182 31482673 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258D0, 0x0, 0x2258C0
  252 31482925 [main] rsync 9964 dtable::select_write:  fd 1
  147 31483072 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
 2211 31485283 [main] rsync 9964 cygwin_select: sel.always_ready 1
  181 31485464 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  148 31485612 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31485755 [main] rsync 9964 set_bits: ready 1
  139 31485894 [main] rsync 9964 select_stuff::poll: returning 1
  139 31486033 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  146 31486179 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  250 31486429 [main] rsync 9964 writev: writev (1, 0x225880, 1)
  143 31486572 [main] rsync 9964 fhandler_base::write: binary write
  169 31486741 [main] rsync 9964 fhandler_base::write: 20 = write (0x225900, 20)
  147 31486888 [main] rsync 9964 writev: 20 = write (1, 0x225880, 1), errno 0
  151 31487039 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258F0, 0x0, 0x2258E0
  259 31487298 [main] rsync 9964 dtable::select_write:  fd 1
  140 31487438 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31487581 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31487722 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
 1655 31489377 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  177 31489554 [main] rsync 9964 set_bits: ready 1
  139 31489693 [main] rsync 9964 select_stuff::poll: returning 1
  140 31489833 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31489974 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
 1679 31491653 [main] rsync 9964 writev: writev (1, 0x2258A0, 1)
  156 31491809 [main] rsync 9964 fhandler_base::write: binary write
  171 31491980 [main] rsync 9964 fhandler_base::write: 40 = write (0x225920, 40)
  153 31492133 [main] rsync 9964 writev: 40 = write (1, 0x2258A0, 1), errno 0
  355 31492488 [main] rsync 9964 close: close (3)
  142 31492630 [main] rsync 9964 fhandler_base::close: closing '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to adobe-pdf.lnk' handle 0x6B4
  303 31492933 [main] rsync 9964 close: 0 = close (3)
  373 31493306 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  256 31493562 [main] rsync 9964 dtable::select_write:  fd 1
  138 31493700 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  140 31493840 [main] rsync 9964 cygwin_select: sel.always_ready 1
  138 31493978 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  179 31494157 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31494300 [main] rsync 9964 set_bits: ready 1
  137 31494437 [main] rsync 9964 select_stuff::poll: returning 1
  138 31494575 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31494714 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  239 31494953 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  144 31495097 [main] rsync 9964 fhandler_base::write: binary write
  161 31495258 [main] rsync 9964 fhandler_base::write: 115 = write (0x225980, 115)
  145 31495403 [main] rsync 9964 writev: 115 = write (1, 0x225900, 1), errno 0
  176 31495579 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  255 31495834 [main] rsync 9964 dtable::select_write:  fd 1
  139 31495973 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  147 31496120 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31496260 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  142 31496402 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31496545 [main] rsync 9964 set_bits: ready 1
  138 31496683 [main] rsync 9964 select_stuff::poll: returning 1
  138 31496821 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31496962 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  248 31497210 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  141 31497351 [main] rsync 9964 fhandler_base::write: binary write
  161 31497512 [main] rsync 9964 fhandler_base::write: 121 = write (0x225980, 121)
  147 31497659 [main] rsync 9964 writev: 121 = write (1, 0x225900, 1), errno 0
  256 31497915 [main] rsync 9964 open: open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk, 0x10000)
  160 31498075 [main] rsync 9964 normalize_posix_path: src /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk
 2894 31500969 [main] rsync 9964 normalize_posix_path: /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk = normalize_posix_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk)
  344 31501313 [main] rsync 9964 mount_info::conv_to_win32_path: conv_to_win32_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk)
  172 31501485 [main] rsync 9964 mount_info::cygdrive_win32_path: src '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk', dst 'c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk'
  159 31501644 [main] rsync 9964 set_flags: flags: binary (0x2)
  140 31501784 [main] rsync 9964 mount_info::conv_to_win32_path: src_path /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk, dst c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, flags 0x22, rc 0
 3871 31505655 [main] rsync 9964 get_symlink_ea: Cannot read symlink from EA
 1043 31506698 [main] rsync 9964 symlink_info::check: not a symlink
  165 31506863 [main] rsync 9964 symlink_info::check: 0 = symlink.check (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 0x226500) (0x22)
  158 31507021 [main] rsync 9964 path_conv::check: root_dir(c:\), this->path(c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk), set_has_acls(8)
  187 31507208 [main] rsync 9964 build_fh_pc: fh 0x616E23D4
  148 31507356 [main] rsync 9964 fhandler_base::open: (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 0x110000) query_open 0
  862 31508218 [main] rsync 9964 fhandler_base::open: 0x6B4 = CreateFile (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 0x80000000, 0x7, 0x226C80, 0x3, 0x2000081, 0)
  183 31508401 [main] rsync 9964 fhandler_base::set_flags: flags 0x110000, supplied_bin 0x10000
  145 31508546 [main] rsync 9964 fhandler_base::set_flags: O_TEXT/O_BINARY set in flags 0x10000
  142 31508688 [main] rsync 9964 fhandler_base::set_flags: filemode set to binary
  141 31508829 [main] rsync 9964 fhandler_base::open: 1 = fhandler_base::open (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 0x110000)
  152 31508981 [main] rsync 9964 fhandler_base::open_fs: 1 = fhandler_disk_file::open (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 0x10000)
  155 31509136 [main] rsync 9964 open: 3 = open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk, 0x10000)
  230 31509366 [main] rsync 9964 fhandler_base::fstat_by_handle: 1 = GetFileInformationByHandle (c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk, 1716)
  708 31510074 [main] rsync 9964 get_file_attribute: file: c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk
  834 31510908 [main] rsync 9964 read_sd: file = c:\Documents and Settings\User Name\Desktop\Adobe old\Shortcut to ar500enuAReader.lnk: len=164
 1130 31512038 [main] rsync 9964 cygpsid::debug_print: get_sids_info: owner SID = S-1-5-21-73586283-854245398-1708537768-1003
  177 31512215 [main] rsync 9964 cygpsid::debug_print: get_sids_info: group SID = S-1-5-21-73586283-854245398-1708537768-513
  156 31512371 [main] rsync 9964 get_info_from_sd: ACL = 1C0, uid 1003, gid 513
  243 31512614 [main] rsync 9964 fhandler_base::fstat_helper: 0 = fstat (, 0x22EEE0) st_atime=40B507E1 st_size=293, st_mode=0x81C0, st_ino=25189, sizeof=96
  148 31512762 [main] rsync 9964 fstat64: 0 = fstat (3, 0x22EEE0)
  468 31513230 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  261 31513491 [main] rsync 9964 dtable::select_write:  fd 1
  142 31513633 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  141 31513774 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31513915 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31514059 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  150 31514209 [main] rsync 9964 set_bits: ready 1
  138 31514347 [main] rsync 9964 select_stuff::poll: returning 1
  139 31514486 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31514627 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  246 31514873 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  143 31515016 [main] rsync 9964 fhandler_base::write: binary write
  175 31515191 [main] rsync 9964 fhandler_base::write: 135 = write (0x225980, 135)
  148 31515339 [main] rsync 9964 writev: 135 = write (1, 0x225900, 1), errno 0
  169 31515508 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225930, 0x0, 0x225920
  251 31515759 [main] rsync 9964 dtable::select_write:  fd 1
  137 31515896 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  171 31516067 [main] rsync 9964 cygwin_select: sel.always_ready 1
  153 31516220 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  143 31516363 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  140 31516503 [main] rsync 9964 set_bits: ready 1
  137 31516640 [main] rsync 9964 select_stuff::poll: returning 1
  136 31516776 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31516915 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  244 31517159 [main] rsync 9964 writev: writev (1, 0x2258E0, 1)
  140 31517299 [main] rsync 9964 fhandler_base::write: binary write
  160 31517459 [main] rsync 9964 fhandler_base::write: 24 = write (0x225960, 24)
  145 31517604 [main] rsync 9964 writev: 24 = write (1, 0x2258E0, 1), errno 0
  147 31517751 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  266 31518017 [main] rsync 9964 dtable::select_write:  fd 1
  226 31518243 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  153 31518396 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31518536 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31518680 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31518823 [main] rsync 9964 set_bits: ready 1
  138 31518961 [main] rsync 9964 select_stuff::poll: returning 1
  138 31519099 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  146 31519245 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  242 31519487 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  142 31519629 [main] rsync 9964 fhandler_base::write: binary write
  161 31519790 [main] rsync 9964 fhandler_base::write: 124 = write (0x225980, 124)
  146 31519936 [main] rsync 9964 writev: 124 = write (1, 0x225900, 1), errno 0
 3946 31523882 [main] rsync 9964 readv: readv (3, 0x226BC0, 1) blocking, sigcatchers 6
  173 31524055 [main] rsync 9964 readv: no need to call ready_for_read
  403 31524458 [main] rsync 9964 fhandler_base::read: returning 293, binary mode
  158 31524616 [main] rsync 9964 readv: 293 = readv (3, 0x226BC0, 1), errno 0
  475 31525091 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258D0, 0x0, 0x2258C0
  276 31525367 [main] rsync 9964 dtable::select_write:  fd 1
  140 31525507 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  142 31525649 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31525789 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  145 31525934 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  144 31526078 [main] rsync 9964 set_bits: ready 1
  144 31526222 [main] rsync 9964 select_stuff::poll: returning 1
  140 31526362 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31526501 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  244 31526745 [main] rsync 9964 writev: writev (1, 0x225880, 1)
  142 31526887 [main] rsync 9964 fhandler_base::write: binary write
  171 31527058 [main] rsync 9964 fhandler_base::write: 189 = write (0x225900, 189)
  152 31527210 [main] rsync 9964 writev: 189 = write (1, 0x225880, 1), errno 0
  148 31527358 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258F0, 0x0, 0x2258E0
  277 31527635 [main] rsync 9964 dtable::select_write:  fd 1
  136 31527771 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  141 31527912 [main] rsync 9964 cygwin_select: sel.always_ready 1
  139 31528051 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  150 31528201 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  141 31528342 [main] rsync 9964 set_bits: ready 1
  136 31528478 [main] rsync 9964 select_stuff::poll: returning 1
  137 31528615 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31528754 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  240 31528994 [main] rsync 9964 writev: writev (1, 0x2258A0, 1)
  145 31529139 [main] rsync 9964 fhandler_base::write: binary write
  200 31529339 [main] rsync 9964 fhandler_base::write: 21 = write (0x225920, 21)
  147 31529486 [main] rsync 9964 writev: 21 = write (1, 0x2258A0, 1), errno 0
  173 31529659 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258D0, 0x0, 0x2258C0
  251 31529910 [main] rsync 9964 dtable::select_write:  fd 1
  139 31530049 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  147 31530196 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31530337 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31530481 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  142 31530623 [main] rsync 9964 set_bits: ready 1
  138 31530761 [main] rsync 9964 select_stuff::poll: returning 1
  139 31530900 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31531039 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  372 31531411 [main] rsync 9964 writev: writev (1, 0x225880, 1)
  147 31531558 [main] rsync 9964 fhandler_base::write: binary write
  163 31531721 [main] rsync 9964 fhandler_base::write: 20 = write (0x225900, 20)
  290 31532011 [main] rsync 9964 writev: 20 = write (1, 0x225880, 1), errno 0
  158 31532169 [main] rsync 9964 cygwin_select: 2, 0x0, 0x2258F0, 0x0, 0x2258E0
 1525 31533694 [main] rsync 9964 dtable::select_write:  fd 1
  142 31533836 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31533979 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31534120 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  152 31534272 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  142 31534414 [main] rsync 9964 set_bits: ready 1
  139 31534553 [main] rsync 9964 select_stuff::poll: returning 1
  139 31534692 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  140 31534832 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  248 31535080 [main] rsync 9964 writev: writev (1, 0x2258A0, 1)
  147 31535227 [main] rsync 9964 fhandler_base::write: binary write
  168 31535395 [main] rsync 9964 fhandler_base::write: 40 = write (0x225920, 40)
  147 31535542 [main] rsync 9964 writev: 40 = write (1, 0x2258A0, 1), errno 0
 2322 31537864 [main] rsync 9964 close: close (3)
  158 31538022 [main] rsync 9964 fhandler_base::close: closing '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/Shortcut to ar500enuAReader.lnk' handle 0x6B4
  239 31538261 [main] rsync 9964 close: 0 = close (3)
  278 31538539 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  255 31538794 [main] rsync 9964 dtable::select_write:  fd 1
  140 31538934 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31539077 [main] rsync 9964 cygwin_select: sel.always_ready 1
  147 31539224 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  146 31539370 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  142 31539512 [main] rsync 9964 set_bits: ready 1
  139 31539651 [main] rsync 9964 select_stuff::poll: returning 1
  140 31539791 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31539932 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  245 31540177 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  151 31540328 [main] rsync 9964 fhandler_base::write: binary write
  169 31540497 [main] rsync 9964 fhandler_base::write: 121 = write (0x225980, 121)
  147 31540644 [main] rsync 9964 writev: 121 = write (1, 0x225900, 1), errno 0
  177 31540821 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  254 31541075 [main] rsync 9964 dtable::select_write:  fd 1
  139 31541214 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  377 31541591 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31541732 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31541876 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  141 31542017 [main] rsync 9964 set_bits: ready 1
  140 31542157 [main] rsync 9964 select_stuff::poll: returning 1
  176 31542333 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31542477 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  242 31542719 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  141 31542860 [main] rsync 9964 fhandler_base::write: binary write
  164 31543024 [main] rsync 9964 fhandler_base::write: 103 = write (0x225980, 103)
  149 31543173 [main] rsync 9964 writev: 103 = write (1, 0x225900, 1), errno 0
  266 31543439 [main] rsync 9964 open: open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe, 0x10000)
  157 31543596 [main] rsync 9964 normalize_posix_path: src /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe
  154 31543750 [main] rsync 9964 normalize_posix_path: /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe = normalize_posix_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe)
  158 31543908 [main] rsync 9964 mount_info::conv_to_win32_path: conv_to_win32_path (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe)
  160 31544068 [main] rsync 9964 mount_info::cygdrive_win32_path: src '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe', dst 'c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe'
  188 31544256 [main] rsync 9964 set_flags: flags: binary (0x2)
  142 31544398 [main] rsync 9964 mount_info::conv_to_win32_path: src_path /cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe, dst c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, flags 0x22, rc 0
 3713 31548111 [main] rsync 9964 symlink_info::check: not a symlink
  207 31548318 [main] rsync 9964 symlink_info::check: 0 = symlink.check (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 0x226500) (0x22)
  156 31548474 [main] rsync 9964 path_conv::check: root_dir(c:\), this->path(c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe), set_has_acls(8)
  174 31548648 [main] rsync 9964 build_fh_pc: fh 0x616E23D4
  149 31548797 [main] rsync 9964 fhandler_base::open: (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 0x110000) query_open 0
131023 31679820 [main] rsync 9964 fhandler_base::open: 0x6B4 = CreateFile (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 0x80000000, 0x7, 0x226C80, 0x3, 0x2000081, 0)
  512 31680332 [main] rsync 9964 fhandler_base::set_flags: flags 0x110000, supplied_bin 0x10000
  155 31680487 [main] rsync 9964 fhandler_base::set_flags: O_TEXT/O_BINARY set in flags 0x10000
  145 31680632 [main] rsync 9964 fhandler_base::set_flags: filemode set to binary
  144 31680776 [main] rsync 9964 fhandler_base::open: 1 = fhandler_base::open (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 0x110000)
  150 31680926 [main] rsync 9964 fhandler_base::open_fs: 1 = fhandler_disk_file::open (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 0x10000)
  150 31681076 [main] rsync 9964 open: 3 = open (/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe, 0x10000)
  301 31681377 [main] rsync 9964 fhandler_base::fstat_by_handle: 1 = GetFileInformationByHandle (c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe, 1716)
  756 31682133 [main] rsync 9964 get_file_attribute: file: c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe
  717 31682850 [main] rsync 9964 read_sd: file = c:\Documents and Settings\User Name\Desktop\Adobe old\adobe-pdf.exe: len=164
  813 31683663 [main] rsync 9964 cygpsid::debug_print: get_sids_info: owner SID = S-1-5-21-73586283-854245398-1708537768-1003
  179 31683842 [main] rsync 9964 cygpsid::debug_print: get_sids_info: group SID = S-1-5-21-73586283-854245398-1708537768-513
  156 31683998 [main] rsync 9964 get_info_from_sd: ACL = 1C0, uid 1003, gid 513
  282 31684280 [main] rsync 9964 fhandler_base::fstat_helper: 0 = fstat (, 0x22EEE0) st_atime=40B50B22 st_size=10236296, st_mode=0x81C0, st_ino=25191, sizeof=96
  150 31684430 [main] rsync 9964 fstat64: 0 = fstat (3, 0x22EEE0)
  534 31684964 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  263 31685227 [main] rsync 9964 dtable::select_write:  fd 1
  142 31685369 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  141 31685510 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31685651 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  150 31685801 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31685944 [main] rsync 9964 set_bits: ready 1
  139 31686083 [main] rsync 9964 select_stuff::poll: returning 1
  140 31686223 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  140 31686363 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  247 31686610 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  148 31686758 [main] rsync 9964 fhandler_base::write: binary write
  173 31686931 [main] rsync 9964 fhandler_base::write: 122 = write (0x225980, 122)
  147 31687078 [main] rsync 9964 writev: 122 = write (1, 0x225900, 1), errno 0
  170 31687248 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225930, 0x0, 0x225920
  255 31687503 [main] rsync 9964 dtable::select_write:  fd 1
  140 31687643 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  146 31687789 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31687929 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  143 31688072 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  144 31688216 [main] rsync 9964 set_bits: ready 1
  137 31688353 [main] rsync 9964 select_stuff::poll: returning 1
  138 31688491 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31688632 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  281 31688913 [main] rsync 9964 writev: writev (1, 0x2258E0, 1)
  141 31689054 [main] rsync 9964 fhandler_base::write: binary write
  161 31689215 [main] rsync 9964 fhandler_base::write: 24 = write (0x225960, 24)
  145 31689360 [main] rsync 9964 writev: 24 = write (1, 0x2258E0, 1), errno 0
  150 31689510 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225950, 0x0, 0x225940
  470 31689980 [main] rsync 9964 dtable::select_write:  fd 1
 2828 31692808 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  186 31692994 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31693135 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  146 31693281 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31693424 [main] rsync 9964 set_bits: ready 1
  139 31693563 [main] rsync 9964 select_stuff::poll: returning 1
  140 31693703 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  245 31693948 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  276 31694224 [main] rsync 9964 writev: writev (1, 0x225900, 1)
  147 31694371 [main] rsync 9964 fhandler_base::write: binary write
  166 31694537 [main] rsync 9964 fhandler_base::write: 106 = write (0x225980, 106)
  148 31694685 [main] rsync 9964 writev: 106 = write (1, 0x225900, 1), errno 0
 2348 31697033 [main] rsync 9964 readv: readv (3, 0x226BC0, 1) blocking, sigcatchers 6
  217 31697250 [main] rsync 9964 readv: no need to call ready_for_read
10321 31707571 [main] rsync 9964 fhandler_base::read: returning 262144, binary mode
  302 31707873 [main] rsync 9964 readv: 262144 = readv (3, 0x226BC0, 1), errno 0
39505 31747378 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  383 31747761 [main] rsync 9964 dtable::select_write:  fd 1
  145 31747906 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31748049 [main] rsync 9964 cygwin_select: sel.always_ready 1
  141 31748190 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  145 31748335 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  182 31748517 [main] rsync 9964 set_bits: ready 1
  140 31748657 [main] rsync 9964 select_stuff::poll: returning 1
  238 31748895 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  148 31749043 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  250 31749293 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  145 31749438 [main] rsync 9964 fhandler_base::write: binary write
  234 31749672 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  155 31749827 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
  188 31750015 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  257 31750272 [main] rsync 9964 dtable::select_write:  fd 1
  136 31750408 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  140 31750548 [main] rsync 9964 cygwin_select: sel.always_ready 1
  139 31750687 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31750828 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  146 31750974 [main] rsync 9964 set_bits: ready 1
  136 31751110 [main] rsync 9964 select_stuff::poll: returning 1
  137 31751247 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  137 31751384 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  414 31751798 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  175 31751973 [main] rsync 9964 fhandler_base::write: binary write
  218 31752191 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  155 31752346 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
  178 31752524 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  259 31752783 [main] rsync 9964 dtable::select_write:  fd 1
  147 31752930 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  144 31753074 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31753214 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31753358 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31753501 [main] rsync 9964 set_bits: ready 1
  138 31753639 [main] rsync 9964 select_stuff::poll: returning 1
  139 31753778 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  143 31753921 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  243 31754164 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  145 31754309 [main] rsync 9964 fhandler_base::write: binary write
  210 31754519 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  155 31754674 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
  176 31754850 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
 2193 31757043 [main] rsync 9964 dtable::select_write:  fd 1
  154 31757197 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  144 31757341 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31757481 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31757625 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  142 31757767 [main] rsync 9964 set_bits: ready 1
  139 31757906 [main] rsync 9964 select_stuff::poll: returning 1
  144 31758050 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  140 31758190 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  245 31758435 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  142 31758577 [main] rsync 9964 fhandler_base::write: binary write
  215 31758792 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
 4553 31763345 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
23133 31786478 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  350 31786828 [main] rsync 9964 dtable::select_write:  fd 1
  139 31786967 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  148 31787115 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31787255 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  186 31787441 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  144 31787585 [main] rsync 9964 set_bits: ready 1
  138 31787723 [main] rsync 9964 select_stuff::poll: returning 1
  139 31787862 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  141 31788003 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
 3276 31791279 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  161 31791440 [main] rsync 9964 fhandler_base::write: binary write
  253 31791693 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  156 31791849 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
12838 31804687 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  337 31805024 [main] rsync 9964 dtable::select_write:  fd 1
  144 31805168 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  141 31805309 [main] rsync 9964 cygwin_select: sel.always_ready 1
  139 31805448 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  144 31805592 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  145 31805737 [main] rsync 9964 set_bits: ready 1
  138 31805875 [main] rsync 9964 select_stuff::poll: returning 1
  139 31806014 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  165 31806179 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  250 31806429 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  143 31806572 [main] rsync 9964 fhandler_base::write: binary write
  242 31806814 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  157 31806971 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
  186 31807157 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  262 31807419 [main] rsync 9964 dtable::select_write:  fd 1
  137 31807556 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31807699 [main] rsync 9964 cygwin_select: sel.always_ready 1
  140 31807839 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  143 31807982 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  147 31808129 [main] rsync 9964 set_bits: ready 1
  140 31808269 [main] rsync 9964 select_stuff::poll: returning 1
  142 31808411 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  139 31808550 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  244 31808794 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  142 31808936 [main] rsync 9964 fhandler_base::write: binary write
  219 31809155 [main] rsync 9964 fhandler_base::write: 4096 = write (0x225BD0, 4096)
  158 31809313 [main] rsync 9964 writev: 4096 = write (1, 0x225B50, 1), errno 0
  178 31809491 [main] rsync 9964 cygwin_select: 2, 0x0, 0x225BA0, 0x0, 0x225B90
  261 31809752 [main] rsync 9964 dtable::select_write:  fd 1
  138 31809890 [main] rsync 9964 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
  143 31810033 [main] rsync 9964 cygwin_select: sel.always_ready 1
  276 31810309 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  149 31810458 [main] rsync 9964 set_bits: me 0x100749D0, testing fd 1 ()
  143 31810601 [main] rsync 9964 set_bits: ready 1
  137 31810738 [main] rsync 9964 select_stuff::poll: returning 1
  139 31810877 [main] rsync 9964 select_stuff::cleanup: calling cleanup routines
  168 31811045 [main] rsync 9964 select_stuff::~select_stuff: deleting select records
  315 31811360 [main] rsync 9964 writev: writev (1, 0x225B50, 1)
  142 31811502 [main] rsync 9964 fhandler_base::write: binary write

[Sent sigterm to process at this point.]
  
129210769 161022271 [sig] rsync 9964 sigpacket::process: signal 9 processing
  293 161022564 [sig] rsync 9964 sigpacket::process: signal 9, about to call do_exit
  308 161022872 [sig] rsync 9964 signal_exit: about to call do_exit (10900)
  121 161022993 [sig] rsync 9964 do_exit: do_exit (67840), exit_state 0
  123 161023116 [sig] rsync 9964 void: 0x40A270 = signal (20, 0x1)
  124 161023240 [sig] rsync 9964 void: 0x4017D0 = signal (1, 0x1)
  151 161023391 [sig] rsync 9964 void: 0x4017D0 = signal (2, 0x1)
  120 161023511 [sig] rsync 9964 void: 0x0 = signal (3, 0x1)
  155 161023666 [sig] rsync 9964 fhandler_base::close: closing '/cygdrive/c/Documents and Settings/User Name/Desktop/Adobe old/adobe-pdf.exe' handle 0x6B4
  221 161023887 [sig] rsync 9964 sigproc_terminate: entering
  154 161024041 [sig] rsync 9964 proc_terminate: nchildren 0, nzombies 0
  124 161024165 [sig] rsync 9964 proc_terminate: leaving
  138 161024303 [sig] rsync 9964 do_exit: 9964 == sid 9964, send SIGHUP to children
  204 161024507 [sig] rsync 9964 tty_list::terminate: tty 1 master about to finish
  227 161024734 [sig] rsync 9964 open: open (/var/run/utmp, 0x10002)
  930 161025664 [sig] rsync 9964 normalize_posix_path: src /var/run/utmp
  172 161025836 [sig] rsync 9964 normalize_posix_path: /var/run/utmp = normalize_posix_path (/var/run/utmp)

[snip]


More information about the rsync mailing list