rsync 2.6.3 hang (was rsync 2.6.2 crash)

Fri Oct 1 21:17:49 GMT 2004

Here are strace outputs of a hung session, 2.6.3 on both ends (server, client process 1, client process 2):

$ strace -t -p 4040
Attached to pid 4040 (windows pid 2488)
14:04:47 [unknown (0x938)] rsync 4040 _cygtls::remove: wait 0x0
14:05:17 [main] rsync 4040 select_stuff::wait: timed out
14:05:17 [main] rsync 4040 select_stuff::wait: returning 1
14:05:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:05:17 [select_pipe] rsync 4040 thread_pipe: stopping
14:05:17 [main] rsync 4040 select_stuff::poll: returning 0
14:05:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:05:17 [main] rsync 4040 select_stuff::~select_stuff: deleting select records
14:05:17 [main] rsync 4040 cygwin_select: 2, 0x0, 0x22D9A0, 0x0, 0x22D990
14:05:17 [main] rsync 4040 dtable::select_write:  fd 1
14:05:17 [main] rsync 4040 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000

14:05:17 [main] rsync 4040 cygwin_select: sel.always_ready 0
14:05:17 [main] rsync 4040 select_stuff::wait: m 2, ms 60000
14:06:17 [main] rsync 4040 select_stuff::wait: timed out
14:06:17 [main] rsync 4040 select_stuff::wait: returning 1
14:06:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:06:17 [select_pipe] rsync 4040 thread_pipe: stopping
14:06:17 [main] rsync 4040 select_stuff::poll: returning 0
14:06:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:06:17 [main] rsync 4040 select_stuff::~select_stuff: deleting select records
14:06:17 [main] rsync 4040 cygwin_select: 2, 0x0, 0x22D9A0, 0x0, 0x22D990
14:06:17 [main] rsync 4040 dtable::select_write:  fd 1
14:06:17 [main] rsync 4040 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000

14:06:17 [main] rsync 4040 cygwin_select: sel.always_ready 0
14:06:17 [main] rsync 4040 select_stuff::wait: m 2, ms 60000
14:07:17 [main] rsync 4040 select_stuff::wait: timed out
14:07:17 [main] rsync 4040 select_stuff::wait: returning 1
14:07:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:07:17 [select_pipe] rsync 4040 thread_pipe: stopping
14:07:17 [main] rsync 4040 select_stuff::poll: returning 0
14:07:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:07:17 [main] rsync 4040 select_stuff::~select_stuff: deleting select records
14:07:17 [main] rsync 4040 cygwin_select: 2, 0x0, 0x22D9A0, 0x0, 0x22D990
14:07:17 [main] rsync 4040 dtable::select_write:  fd 1
14:07:17 [main] rsync 4040 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000

14:07:17 [main] rsync 4040 cygwin_select: sel.always_ready 0
14:07:17 [main] rsync 4040 select_stuff::wait: m 2, ms 60000
14:08:17 [main] rsync 4040 select_stuff::wait: timed out
14:08:17 [main] rsync 4040 select_stuff::wait: returning 1
14:08:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:08:17 [select_pipe] rsync 4040 thread_pipe: stopping
14:08:17 [main] rsync 4040 select_stuff::poll: returning 0
14:08:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:08:17 [main] rsync 4040 select_stuff::~select_stuff: deleting select records
14:08:17 [main] rsync 4040 cygwin_select: 2, 0x0, 0x22D9A0, 0x0, 0x22D990
14:08:17 [main] rsync 4040 dtable::select_write:  fd 1
14:08:17 [main] rsync 4040 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000

14:08:17 [main] rsync 4040 cygwin_select: sel.always_ready 0
14:08:17 [main] rsync 4040 select_stuff::wait: m 2, ms 60000
14:09:17 [main] rsync 4040 select_stuff::wait: timed out
14:09:17 [main] rsync 4040 select_stuff::wait: returning 1
14:09:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:09:17 [select_pipe] rsync 4040 thread_pipe: stopping
14:09:17 [main] rsync 4040 select_stuff::poll: returning 0
14:09:17 [main] rsync 4040 select_stuff::cleanup: calling cleanup routines
14:09:17 [main] rsync 4040 select_stuff::~select_stuff: deleting select records
14:09:17 [main] rsync 4040 cygwin_select: 2, 0x0, 0x22D9A0, 0x0, 0x22D990
14:09:17 [main] rsync 4040 dtable::select_write:  fd 1
14:09:17 [main] rsync 4040 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000

14:09:17 [main] rsync 4040 cygwin_select: sel.always_ready 0
14:09:17 [main] rsync 4040 select_stuff::wait: m 2, ms 60000

----------------------------------------------------

client 1:
$ strace -t -p 2472
Attached to pid 2472 (windows pid 1248)
14:03:19 [unknown (0x528)] rsync 2472 _cygtls::remove: wait 0x0
14:03:19 [unknown (0x528)] rsync 2472 _cygtls::remove: removed 0xF8F140 element 1
14:04:19 [main] rsync 2472 select_stuff::wait: timed out
14:04:19 [main] rsync 2472 select_stuff::wait: returning 1
14:04:19 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:04:19 [main] rsync 2472 socket_cleanup: si 0x101A65C8 si->thread 0x610F38D8
14:04:19 [main] rsync 2472 socket_cleanup: connection to si->exitsock 0x2E4
14:04:19 [select_socket] rsync 2472 thread_socket: Win32 select returned 1
14:04:19 [select_socket] rsync 2472 thread_socket: s 0x10013780, testing fd 4 ()
14:04:19 [select_socket] rsync 2472 thread_socket: s 0x10012A78, testing fd 3 ()
14:04:19 [select_socket] rsync 2472 thread_socket: saw exitsock read
14:04:19 [main] rsync 2472 socket_cleanup: returning
14:04:19 [main] rsync 2472 socket_cleanup: si 0x0 si->thread 0x0
14:04:19 [main] rsync 2472 socket_cleanup: returning
14:04:19 [main] rsync 2472 peek_socket: considering handle 0x308
14:04:19 [main] rsync 2472 peek_socket: adding write fd_set , fd 4
14:04:19 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:04:19 [main] rsync 2472 peek_socket: considering handle 0x2C0
14:04:19 [main] rsync 2472 peek_socket: adding read fd_set , fd 3
14:04:19 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:04:19 [main] rsync 2472 select_stuff::poll: returning 0
14:04:19 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:04:19 [main] rsync 2472 select_stuff::~select_stuff: deleting select records
14:04:19 [main] rsync 2472 cygwin_select: 5, 0x22E9C8, 0x22E9D0, 0x0, 0x22E9C0
14:04:19 [main] rsync 2472 dtable::select_read:  fd 3
14:04:19 [main] rsync 2472 dtable::select_write:  fd 4
14:04:19 [main] rsync 2472 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:04:19 [main] rsync 2472 cygwin_select: sel.always_ready 0
14:04:19 [main] rsync 2472 start_thread_socket: Handle 0x308
14:04:19 [main] rsync 2472 start_thread_socket: Added to writefds
14:04:19 [main] rsync 2472 start_thread_socket: Handle 0x2C0
14:04:19 [main] rsync 2472 start_thread_socket: Added to readfds
14:04:19 [main] rsync 2472 start_thread_socket: exitsock 0x2E4
14:04:19 [main] rsync 2472 start_thread_socket: stuff_start 0x22E934
14:04:19 [main] rsync 2472 select_stuff::wait: m 2, ms 60000
14:04:20 [select_socket] rsync 2472 thread_socket: stuff_start 0x101A95EC
14:05:20 [main] rsync 2472 select_stuff::wait: timed out
14:05:20 [main] rsync 2472 select_stuff::wait: returning 1
14:05:20 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:05:20 [main] rsync 2472 socket_cleanup: si 0x101A65C8 si->thread 0x610F38D8
14:05:20 [main] rsync 2472 socket_cleanup: connection to si->exitsock 0x2E4
14:05:20 [select_socket] rsync 2472 thread_socket: Win32 select returned 1
14:05:20 [select_socket] rsync 2472 thread_socket: s 0x10013780, testing fd 4 ()
14:05:20 [select_socket] rsync 2472 thread_socket: s 0x10012A78, testing fd 3 ()
14:05:20 [select_socket] rsync 2472 thread_socket: saw exitsock read
14:05:20 [main] rsync 2472 socket_cleanup: returning
14:05:20 [main] rsync 2472 socket_cleanup: si 0x0 si->thread 0x0
14:05:20 [main] rsync 2472 socket_cleanup: returning
14:05:20 [main] rsync 2472 peek_socket: considering handle 0x308
14:05:20 [main] rsync 2472 peek_socket: adding write fd_set , fd 4
14:05:20 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:05:20 [main] rsync 2472 peek_socket: considering handle 0x2C0
14:05:20 [main] rsync 2472 peek_socket: adding read fd_set , fd 3
14:05:20 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:05:20 [main] rsync 2472 select_stuff::poll: returning 0
14:05:20 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:05:20 [main] rsync 2472 select_stuff::~select_stuff: deleting select records
14:05:20 [main] rsync 2472 cygwin_select: 5, 0x22E9C8, 0x22E9D0, 0x0, 0x22E9C0
14:05:20 [main] rsync 2472 dtable::select_read:  fd 3
14:05:20 [main] rsync 2472 dtable::select_write:  fd 4
14:05:20 [main] rsync 2472 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:05:20 [main] rsync 2472 cygwin_select: sel.always_ready 0
14:05:20 [main] rsync 2472 start_thread_socket: Handle 0x308
14:05:20 [main] rsync 2472 start_thread_socket: Added to writefds
14:05:20 [main] rsync 2472 start_thread_socket: Handle 0x2C0
14:05:20 [main] rsync 2472 start_thread_socket: Added to readfds
14:05:20 [main] rsync 2472 start_thread_socket: exitsock 0x2E4
14:05:20 [main] rsync 2472 start_thread_socket: stuff_start 0x22E934
14:05:20 [main] rsync 2472 select_stuff::wait: m 2, ms 60000
14:05:20 [select_socket] rsync 2472 thread_socket: stuff_start 0x101A95EC
14:06:20 [main] rsync 2472 select_stuff::wait: timed out
14:06:20 [main] rsync 2472 select_stuff::wait: returning 1
14:06:20 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:06:20 [main] rsync 2472 socket_cleanup: si 0x101A65C8 si->thread 0x610F38D8
14:06:20 [main] rsync 2472 socket_cleanup: connection to si->exitsock 0x2E4
14:06:20 [select_socket] rsync 2472 thread_socket: Win32 select returned 1
14:06:20 [select_socket] rsync 2472 thread_socket: s 0x10013780, testing fd 4 ()
14:06:20 [select_socket] rsync 2472 thread_socket: s 0x10012A78, testing fd 3 ()
14:06:20 [select_socket] rsync 2472 thread_socket: saw exitsock read
14:06:20 [main] rsync 2472 socket_cleanup: returning
14:06:20 [main] rsync 2472 socket_cleanup: si 0x0 si->thread 0x0
14:06:20 [main] rsync 2472 socket_cleanup: returning
14:06:20 [main] rsync 2472 peek_socket: considering handle 0x308
14:06:20 [main] rsync 2472 peek_socket: adding write fd_set , fd 4
14:06:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:06:21 [main] rsync 2472 peek_socket: considering handle 0x2C0
14:06:21 [main] rsync 2472 peek_socket: adding read fd_set , fd 3
14:06:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:06:21 [main] rsync 2472 select_stuff::poll: returning 0
14:06:21 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:06:21 [main] rsync 2472 select_stuff::~select_stuff: deleting select records
14:06:21 [main] rsync 2472 cygwin_select: 5, 0x22E9C8, 0x22E9D0, 0x0, 0x22E9C0
14:06:21 [main] rsync 2472 dtable::select_read:  fd 3
14:06:21 [main] rsync 2472 dtable::select_write:  fd 4
14:06:21 [main] rsync 2472 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:06:21 [main] rsync 2472 cygwin_select: sel.always_ready 0
14:06:21 [main] rsync 2472 start_thread_socket: Handle 0x308
14:06:21 [main] rsync 2472 start_thread_socket: Added to writefds
14:06:21 [main] rsync 2472 start_thread_socket: Handle 0x2C0
14:06:21 [main] rsync 2472 start_thread_socket: Added to readfds
14:06:21 [main] rsync 2472 start_thread_socket: exitsock 0x2E4
14:06:21 [main] rsync 2472 start_thread_socket: stuff_start 0x22E934
14:06:21 [main] rsync 2472 select_stuff::wait: m 2, ms 60000
14:06:21 [select_socket] rsync 2472 thread_socket: stuff_start 0x101A95EC
14:07:21 [main] rsync 2472 select_stuff::wait: timed out
14:07:21 [main] rsync 2472 select_stuff::wait: returning 1
14:07:21 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:07:21 [main] rsync 2472 socket_cleanup: si 0x101A65C8 si->thread 0x610F38D8
14:07:21 [main] rsync 2472 socket_cleanup: connection to si->exitsock 0x2E4
14:07:21 [select_socket] rsync 2472 thread_socket: Win32 select returned 1
14:07:21 [select_socket] rsync 2472 thread_socket: s 0x10013780, testing fd 4 ()
14:07:21 [select_socket] rsync 2472 thread_socket: s 0x10012A78, testing fd 3 ()
14:07:21 [select_socket] rsync 2472 thread_socket: saw exitsock read
14:07:21 [main] rsync 2472 socket_cleanup: returning
14:07:21 [main] rsync 2472 socket_cleanup: si 0x0 si->thread 0x0
14:07:21 [main] rsync 2472 socket_cleanup: returning
14:07:21 [main] rsync 2472 peek_socket: considering handle 0x308
14:07:21 [main] rsync 2472 peek_socket: adding write fd_set , fd 4
14:07:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:07:21 [main] rsync 2472 peek_socket: considering handle 0x2C0
14:07:21 [main] rsync 2472 peek_socket: adding read fd_set , fd 3
14:07:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:07:21 [main] rsync 2472 select_stuff::poll: returning 0
14:07:21 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:07:21 [main] rsync 2472 select_stuff::~select_stuff: deleting select records
14:07:21 [main] rsync 2472 cygwin_select: 5, 0x22E9C8, 0x22E9D0, 0x0, 0x22E9C0
14:07:21 [main] rsync 2472 dtable::select_read:  fd 3
14:07:21 [main] rsync 2472 dtable::select_write:  fd 4
14:07:21 [main] rsync 2472 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:07:21 [main] rsync 2472 cygwin_select: sel.always_ready 0
14:07:21 [main] rsync 2472 start_thread_socket: Handle 0x308
14:07:21 [main] rsync 2472 start_thread_socket: Added to writefds
14:07:21 [main] rsync 2472 start_thread_socket: Handle 0x2C0
14:07:21 [main] rsync 2472 start_thread_socket: Added to readfds
14:07:21 [main] rsync 2472 start_thread_socket: exitsock 0x2E4
14:07:21 [main] rsync 2472 start_thread_socket: stuff_start 0x22E934
14:07:21 [main] rsync 2472 select_stuff::wait: m 2, ms 60000
14:07:21 [select_socket] rsync 2472 thread_socket: stuff_start 0x101A95EC
14:08:21 [main] rsync 2472 select_stuff::wait: timed out
14:08:21 [main] rsync 2472 select_stuff::wait: returning 1
14:08:21 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:08:21 [main] rsync 2472 socket_cleanup: si 0x101A65C8 si->thread 0x610F38D8
14:08:21 [main] rsync 2472 socket_cleanup: connection to si->exitsock 0x2E4
14:08:21 [select_socket] rsync 2472 thread_socket: Win32 select returned 1
14:08:21 [select_socket] rsync 2472 thread_socket: s 0x10013780, testing fd 4 ()
14:08:21 [select_socket] rsync 2472 thread_socket: s 0x10012A78, testing fd 3 ()
14:08:21 [select_socket] rsync 2472 thread_socket: saw exitsock read
14:08:21 [main] rsync 2472 socket_cleanup: returning
14:08:21 [main] rsync 2472 socket_cleanup: si 0x0 si->thread 0x0
14:08:21 [main] rsync 2472 socket_cleanup: returning
14:08:21 [main] rsync 2472 peek_socket: considering handle 0x308
14:08:21 [main] rsync 2472 peek_socket: adding write fd_set , fd 4
14:08:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:08:21 [main] rsync 2472 peek_socket: considering handle 0x2C0
14:08:21 [main] rsync 2472 peek_socket: adding read fd_set , fd 3
14:08:21 [main] rsync 2472 peek_socket: WINSOCK_SELECT returned 0
14:08:21 [main] rsync 2472 select_stuff::poll: returning 0
14:08:21 [main] rsync 2472 select_stuff::cleanup: calling cleanup routines
14:08:21 [main] rsync 2472 select_stuff::~select_stuff: deleting select records
14:08:21 [main] rsync 2472 cygwin_select: 5, 0x22E9C8, 0x22E9D0, 0x0, 0x22E9C0
14:08:21 [main] rsync 2472 dtable::select_read:  fd 3
14:08:21 [main] rsync 2472 dtable::select_write:  fd 4
14:08:21 [main] rsync 2472 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:08:21 [main] rsync 2472 cygwin_select: sel.always_ready 0
14:08:21 [main] rsync 2472 start_thread_socket: Handle 0x308
14:08:21 [main] rsync 2472 start_thread_socket: Added to writefds
14:08:21 [main] rsync 2472 start_thread_socket: Handle 0x2C0
14:08:21 [main] rsync 2472 start_thread_socket: Added to readfds
14:08:21 [main] rsync 2472 start_thread_socket: exitsock 0x2E4
14:08:21 [main] rsync 2472 start_thread_socket: stuff_start 0x22E934
14:08:21 [main] rsync 2472 select_stuff::wait: m 2, ms 60000
14:08:21 [select_socket] rsync 2472 thread_socket: stuff_start 0x101A95EC

client 2:

$ strace -t -p1468
Attached to pid 1468 (windows pid 1468)
14:03:40 [unknown (0x9F4)] rsync 1468 _cygtls::remove: wait 0x0
14:03:40 [unknown (0x9F4)] rsync 1468 _cygtls::remove: removed 0xDCF140 element 1
14:04:19 [main] rsync 1468 select_stuff::wait: timed out
14:04:19 [main] rsync 1468 select_stuff::wait: returning 1
14:04:19 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:04:19 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:04:19 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:04:20 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:04:20 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:04:20 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:04:20 [main] rsync 1468 socket_cleanup: returning
14:04:20 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:04:20 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:04:20 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:04:20 [main] rsync 1468 select_stuff::poll: returning 0
14:04:20 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:04:20 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:04:20 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:04:20 [main] rsync 1468 dtable::select_read:  fd 5
14:04:20 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:04:20 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:04:20 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:04:20 [main] rsync 1468 start_thread_socket: Added to readfds
14:04:20 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:04:20 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:04:20 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:04:20 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C
14:05:20 [main] rsync 1468 select_stuff::wait: timed out
14:05:20 [main] rsync 1468 select_stuff::wait: returning 1
14:05:20 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:05:20 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:05:20 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:05:20 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:05:20 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:05:20 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:05:20 [main] rsync 1468 socket_cleanup: returning
14:05:20 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:05:20 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:05:20 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:05:20 [main] rsync 1468 select_stuff::poll: returning 0
14:05:20 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:05:20 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:05:20 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:05:20 [main] rsync 1468 dtable::select_read:  fd 5
14:05:20 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:05:20 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:05:20 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:05:20 [main] rsync 1468 start_thread_socket: Added to readfds
14:05:20 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:05:20 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:05:20 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:05:20 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C
14:06:20 [main] rsync 1468 select_stuff::wait: timed out
14:06:20 [main] rsync 1468 select_stuff::wait: returning 1
14:06:20 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:06:20 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:06:20 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:06:20 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:06:20 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:06:20 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:06:20 [main] rsync 1468 socket_cleanup: returning
14:06:20 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:06:20 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:06:20 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:06:20 [main] rsync 1468 select_stuff::poll: returning 0
14:06:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:06:21 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:06:21 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:06:21 [main] rsync 1468 dtable::select_read:  fd 5
14:06:21 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:06:21 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:06:21 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:06:21 [main] rsync 1468 start_thread_socket: Added to readfds
14:06:21 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:06:21 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:06:21 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:06:21 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C
14:07:21 [main] rsync 1468 select_stuff::wait: timed out
14:07:21 [main] rsync 1468 select_stuff::wait: returning 1
14:07:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:07:21 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:07:21 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:07:21 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:07:21 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:07:21 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:07:21 [main] rsync 1468 socket_cleanup: returning
14:07:21 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:07:21 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:07:21 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:07:21 [main] rsync 1468 select_stuff::poll: returning 0
14:07:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:07:21 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:07:21 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:07:21 [main] rsync 1468 dtable::select_read:  fd 5
14:07:21 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:07:21 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:07:21 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:07:21 [main] rsync 1468 start_thread_socket: Added to readfds
14:07:21 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:07:21 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:07:21 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:07:21 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C
14:08:21 [main] rsync 1468 select_stuff::wait: timed out
14:08:21 [main] rsync 1468 select_stuff::wait: returning 1
14:08:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:08:21 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:08:21 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:08:21 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:08:21 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:08:21 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:08:21 [main] rsync 1468 socket_cleanup: returning
14:08:21 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:08:21 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:08:21 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:08:21 [main] rsync 1468 select_stuff::poll: returning 0
14:08:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:08:21 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:08:21 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:08:21 [main] rsync 1468 dtable::select_read:  fd 5
14:08:21 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:08:21 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:08:21 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:08:21 [main] rsync 1468 start_thread_socket: Added to readfds
14:08:21 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:08:21 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:08:21 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:08:21 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C
14:09:21 [main] rsync 1468 select_stuff::wait: timed out
14:09:21 [main] rsync 1468 select_stuff::wait: returning 1
14:09:21 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:09:21 [main] rsync 1468 socket_cleanup: si 0x101BB968 si->thread 0x610F38B0
14:09:21 [main] rsync 1468 socket_cleanup: connection to si->exitsock 0x324
14:09:21 [select_socket] rsync 1468 thread_socket: Win32 select returned 1
14:09:21 [select_socket] rsync 1468 thread_socket: s 0x10012A58, testing fd 5 ()
14:09:21 [select_socket] rsync 1468 thread_socket: saw exitsock read
14:09:21 [main] rsync 1468 socket_cleanup: returning
14:09:21 [main] rsync 1468 peek_socket: considering handle 0x3E8
14:09:21 [main] rsync 1468 peek_socket: adding read fd_set , fd 5
14:09:22 [main] rsync 1468 peek_socket: WINSOCK_SELECT returned 0
14:09:22 [main] rsync 1468 select_stuff::poll: returning 0
14:09:22 [main] rsync 1468 select_stuff::cleanup: calling cleanup routines
14:09:22 [main] rsync 1468 select_stuff::~select_stuff: deleting select records
14:09:22 [main] rsync 1468 cygwin_select: 6, 0x22E4C0, 0x22E4B8, 0x0, 0x22E4B0
14:09:22 [main] rsync 1468 dtable::select_read:  fd 5
14:09:22 [main] rsync 1468 cygwin_select: to->tv_sec 60, to->tv_usec 0, ms 60000
14:09:22 [main] rsync 1468 cygwin_select: sel.always_ready 0
14:09:22 [main] rsync 1468 start_thread_socket: Handle 0x3E8
14:09:22 [main] rsync 1468 start_thread_socket: Added to readfds
14:09:22 [main] rsync 1468 start_thread_socket: exitsock 0x324
14:09:22 [main] rsync 1468 start_thread_socket: stuff_start 0x22E434
14:09:22 [main] rsync 1468 select_stuff::wait: m 2, ms 60000
14:09:22 [select_socket] rsync 1468 thread_socket: stuff_start 0x101BE98C