[Bug 10035] New: rsync hangs in solaris

samba-bugs at samba.org samba-bugs at samba.org
Thu Jul 18 10:23:17 MDT 2013


https://bugzilla.samba.org/show_bug.cgi?id=10035

           Summary: rsync hangs in solaris
           Product: rsync
           Version: 3.0.9
          Platform: All
        OS/Version: All
            Status: NEW
          Severity: normal
          Priority: P5
         Component: core
        AssignedTo: wayned at samba.org
        ReportedBy: nestor.urquiza at gmail.com
         QAContact: rsync-qa at samba.org


Using Solaris SunOS 5.10 in two machines we notice from time to time rsync
hanging. Below are the results from truss on two pids from one client rsync and
the results on the server side from 4 different pids as we rsync from two
different clients. Please let me know of anything else you would like me to try
on my end. All servers are running the same rsync version:
$ /opt/csw/bin/rsync --version
rsync  version 3.0.9  protocol version 30
Copyright (C) 1996-2011 by Andrew Tridgell, Wayne Davison, and others.
Web site: http://rsync.samba.org/
Capabilities:
    64-bit files, 64-bit inums, 32-bit timestamps, 64-bit long ints,
    socketpairs, hardlinks, symlinks, IPv6, batchfiles, inplace,
    append, ACLs, no xattrs, iconv, no symtimes


A typical client interaction:
10530:  pollsys(0x08047850, 2, 0x00000000, 0x00000000)  = 1
10530:  read(4, " BF9CEC9AD931BD5E4A78FE8".., 8192)     = 128
10530:  pollsys(0x08047850, 3, 0x00000000, 0x00000000)  = 1
10530:  write(7, " P\0\0\n r s y n c   e r".., 84)      = 84
10530:  pollsys(0x08047850, 2, 0x00000000, 0x00000000)  = 1
10530:  read(4, "93B8 #BA 784F9CF 918 .B6".., 8192)     = 128
10530:  pollsys(0x08047850, 3, 0x00000000, 0x00000000)  = 1
10530:  write(7, " M\0\0\t [ s e n d e r ]".., 81)      = 81
10530:  pollsys(0x08047850, 2, 0x00000000, 0x00000000)  = 1
10530:  read(4, "\n98C7 lC2D2FF k jED8AEE".., 8192)     = 128
10530:  close(7)                                        = 0
10530:  close(6)                                        = 0
10530:  close(8)                                        = 0
10530:  pollsys(0x08047850, 1, 0x00000000, 0x00000000)  = 1
10530:  write(4, "9094 t PF4958E9A > 41C ^".., 32)      = 32
10530:  ioctl(0, TCGETA, 0x08047988)                    Err#22 EINVAL
10530:  fcntl(0, F_GETFL)                               = 130
10530:  fcntl(0, F_SETFL, FWRITE)                       = 0
10530:  ioctl(1, TCGETA, 0x08047988)                    Err#22 EINVAL
10530:  fcntl(1, F_GETFL)                               = 130
10530:  fcntl(1, F_SETFL, FWRITE)                       = 0
10530:  ioctl(2, TCGETA, 0x08047988)                    Err#22 EINVAL
10530:  fcntl(2, F_GETFL)                               = 2
10530:  shutdown(4, SHUT_RDWR, SOV_DEFAULT)             = 0
10530:  close(4)                                        = 0
10530:  close(5)                                        = 0
10530:  _exit(30)
10529:      Received signal #18, SIGCLD, in write() [caught]
10529:        siginfo: SIGCLD CLD_EXITED pid=10530 status=0x001E
10529:  write(1, " r e c v _ f i l e _ n a".., 42)      Err#4 EINTR
10529:  lwp_sigmask(SIG_SETMASK, 0x00020000, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
10529:  waitid(P_ALL, 0, 0x08045220, WEXITED|WTRAPPED|WNOHANG) = 0
10529:  waitid(P_ALL, 0, 0x08045220, WEXITED|WTRAPPED|WNOHANG) Err#10 ECHILD
10529:  setcontext(0x08045110)
10529:  pollsys(0x08045D80, 1, 0x08045E10, 0x00000000)  = 1
10529:  read(7, " (\0\0\t", 4)                          = 4
10529:  time()                                          = 1374162335
10529:  pollsys(0x08045D80, 1, 0x08045E10, 0x00000000)  = 1
10529:  read(7, " [ s e n d e r ]   m a k".., 40)       = 40
10529:  time()                                          = 1374162335
10529:  write(1, 0x080C1FE4, 40)        (sleeping...)



A typical server interaction(multiple pids):
1. After several:
24874:  pollsys(0x08045470, 1, 0x080454D0, 0x00000000)  = 0
24874:  time()                                          = 1374162034
24874:  pollsys(0x08045470, 1, 0x080454D0, 0x00000000) (sleeping...)

at timeout we get:
24830:  write(1, " M\0\0\t [ s e n d e r ]".., 81)      = 81
24830:  time()                                          = 1374162319
24830:  pollsys(0x080453E0, 0, 0x08045430, 0x00000000)  = 0
24830:  _exit(30)
24827:  waitid(P_ALL, 0, 0x08047A20,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOWAIT) = 0
24827:  times(0x08047A10)                               = 1387016957
24827:  waitid(P_ALL, 0, 0x08047990,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOHANG) = 0
24827:  times(0x08047A20)                               = 1387016957
24827:  times(0x08047A10)                               = 1387016957
24827:  waitid(P_ALL, 0, 0x08047990,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOHANG) Err#10 ECHILD
24827:  lwp_sigmask(SIG_SETMASK, 0x00020002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24827:  lwp_sigmask(SIG_SETMASK, 0x00000002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24827:  lwp_sigmask(SIG_SETMASK, 0x00000002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24827:  lwp_sigmask(SIG_SETMASK, 0x00000000, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24827:  _exit(30)
24874:  pollsys(0x08045470, 1, 0x080454D0, 0x00000000)  = 0
24874:  time()                                          = 1374162334
24874:  sigaction(SIGUSR1, 0x08045460, 0x00000000)      = 0
24874:  sigaction(SIGUSR2, 0x08045460, 0x00000000)      = 0
24874:  pollsys(0x080418F0, 1, 0x08041950, 0x00000000)  = 1
24874:  write(1, " P\0\0\n r s y n c   e r".., 84)      = 84
24874:  time()                                          = 1374162334
24874:  pollsys(0x08041930, 1, 0x08041990, 0x00000000)  = 1
24874:  write(1, " M\0\0\t [ s e n d e r ]".., 81)      = 81
24874:  time()                                          = 1374162334
24874:  pollsys(0x080453E0, 0, 0x08045430, 0x00000000)  = 0
24874:  _exit(30)
24871:  waitid(P_ALL, 0, 0x08047A20,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOWAIT) = 0
24871:  times(0x08047A10)                               = 1387018547
24871:  waitid(P_ALL, 0, 0x08047990,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOHANG) = 0
24871:  times(0x08047A20)                               = 1387018547
24871:  times(0x08047A10)                               = 1387018547
24871:  waitid(P_ALL, 0, 0x08047990,
WEXITED|WTRAPPED|WSTOPPED|WCONTINUED|WNOHANG) Err#10 ECHILD
24871:  lwp_sigmask(SIG_SETMASK, 0x00020002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24871:  lwp_sigmask(SIG_SETMASK, 0x00000002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24871:  lwp_sigmask(SIG_SETMASK, 0x00000002, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24871:  lwp_sigmask(SIG_SETMASK, 0x00000000, 0x00000000) = 0xFFBFFEFF
[0x0000FFFF]
24871:  _exit(30)

Thanks!
- Nestor

-- 
Configure bugmail: https://bugzilla.samba.org/userprefs.cgi?tab=email
------- You are receiving this mail because: -------
You are the QA contact for the bug.


More information about the rsync mailing list