Hi,

Thanks for your reply. I now also suspect it is network related. The strange 
thing is that I also tried to restore a small file to the remote system and 
it worked fine. The tool I use (portfwd) has been used by me in other cases 
plenty of times with multi-gigabytes of transfered data.. Never had a 
problem. 

Without the "-c" in strace I get a lot of:
5784  syscall_383(0x809cf4c)            = 0x17f
5784  syscall_383(0x809cf4c)            = 0
5784  syscall_383(0x809cf4c)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf4c)            = 0x17f
5784  syscall_383(0x809cf4c)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0
5784  syscall_383(0x809cf40)            = 0

To be exact it hangs on:
5784  syscall_383(0x809cf40
for a couple of seconds and than repeats.

With the "-c" I get:
% time     seconds  usecs/call     calls    errors syscall
------ ----------- ----------- --------- --------- ----------------
 74.20    0.011874          11      1042           read
  6.29    0.001007          20        50        38 access
  3.61    0.000577          16        36           mmap
  2.91    0.000466          25        19         4 open
  2.54    0.000406          16        25           break
  1.79    0.000287         287         1           execve
  1.48    0.000237          16        15           close
  1.36    0.000218          11        20           mprotect
  1.17    0.000187          17        11           munmap
  0.90    0.000144          18         8           ioctl
  0.79    0.000127          11        12           fstat
  0.57    0.000091          23         4         2 stat
  0.43    0.000069          10         7           sigprocmask
  0.34    0.000055          14         4           __sysctl
  0.22    0.000036           9         4           issetugid
  0.18    0.000029          29         1         1 readlink
  0.18    0.000029          10         3           lseek
  0.14    0.000023          12         2           gettimeofday
  0.13    0.000021          21         1           write
  0.12    0.000020          10         2           getpid
  0.12    0.000019          10         2           getuid
  0.12    0.000019          10         2           sysarch
  0.08    0.000013          13         1           __getcwd
  0.07    0.000011          11         1           fcntl
  0.06    0.000010          10         1           sigaltstack
  0.06    0.000009           9         1           geteuid
  0.06    0.000009           9         1           getegid
  0.06    0.000009           9         1           getgid
------ ----------- ----------- --------- --------- ----------------
100.00    0.016002                  1277        45 total


Which suggests to me that you are right about suspecting the forwarding; 
read is about 3/4 of the time.

I think I'll have to resort to a normal BSD style forwarding and see how 
that works out. 

J.


Dep, Khushil (GE Money) writes:

> You may also find running sudo strace -fF -o list.out ./bconsole
> helpful. Once it hangs - give it a few minutes - then bomb out and post
> the list.out file here for us to take a look at :-) 
> 
> -----Original Message-----
> From: Dep, Khushil (GE Money) 
> Sent: 30 October 2007 15:50
> To: Dep, Khushil (GE Money); Johan van Vliet;
> bacula-users@lists.sourceforge.net
> Subject: RE: [Bacula-users] Hanging after starting job and executing
> status
> 
> Here's the output of mine:
> 
> sudo strace -cfF -o list.out ./bconsole
> 
> % time     seconds  usecs/call     calls    errors syscall
> ------ ----------- ----------- --------- --------- ----------------
>  38.93    0.038702        4838         8         4 waitpid
>  17.11    0.017006        1546        11         1 futex
>  15.53    0.015440         102       151           write
>  12.40    0.012325          37       330           read
>   3.17    0.003149         787         4           execve
>   2.30    0.002289          41        56           rt_sigprocmask
>   1.63    0.001620          32        51           close
>   1.62    0.001609         322         5           clone
>   1.45    0.001441          34        42         7 open
>   1.08    0.001072          12        91           rt_sigaction
>   0.79    0.000783          19        42           old_mmap
>   0.70    0.000700          44        16           munmap
>   0.53    0.000528         176         3         2 connect
>   0.35    0.000344          10        36           fstat64
>   0.29    0.000290          13        22           mmap2
>   0.29    0.000289          16        18         2 ioctl
>   0.26    0.000256          28         9           mprotect
>   0.22    0.000223          12        18         9 stat64
>   0.14    0.000137          11        12           time
>   0.12    0.000121          10        12           brk
>   0.12    0.000118          12        10           gettimeofday
>   0.11    0.000111          28         4           sigreturn
>   0.11    0.000105          13         8         4 access
>   0.10    0.000104          15         7           uname
>   0.09    0.000086          29         3           socket
>   0.08    0.000075           8         9           select
>   0.06    0.000059           8         7           _llseek
>   0.06    0.000059           7         8           fcntl64
>   0.05    0.000053          13         4           set_thread_area
>   0.05    0.000045          45         1           _sysctl
>   0.05    0.000045          11         4           clock_gettime
>   0.04    0.000035           9         4           dup2
>   0.03    0.000033          17         2           pipe
>   0.03    0.000026          13         2           setsockopt
>   0.02    0.000024           6         4           nanosleep
>   0.02    0.000023          12         2           geteuid32
>   0.02    0.000018           9         2           getuid32
>   0.02    0.000016           8         2           getegid32
>   0.01    0.000014           7         2           getrlimit
>   0.01    0.000012           6         2           getgid32
>   0.01    0.000009           9         1           getpgrp
>   0.01    0.000008           8         1           getpid
>   0.01    0.000008           8         1           getppid
>   0.01    0.000008           8         1           set_tid_address
> ------ ----------- ----------- --------- --------- ----------------
> 100.00    0.099418                  1028        29 total 
> 
> 
> That's without errors. If you run that against your bconsole you should
> see where it's bombing? Anyone else?

J.

-------------------------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to