Bacula-users

Re: [Bacula-users] Bnet receive challenge response error ? Authentication errors...

2010-01-14 10:50:37
Subject: Re: [Bacula-users] Bnet receive challenge response error ? Authentication errors...
From: Simon Spring - GoldNet GmbH <spring AT goldnet DOT ch>
To: Bacula-users AT lists.sourceforge DOT net
Date: Thu, 14 Jan 2010 16:47:40 +0100
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

__Martin

Many thanks for your reply, i have two things here (which are quite  
long):

First, another debug of baculca-fd where something diffrent in the  
debug of:
(Note: Nothing changed in configuration etc... )
- ---
$> bacula-fd -f -u root -g bacula -c /etc/bacula/bacula-fd.conf -v -s - 
d250
bacula-fd: filed_conf.c:438-0 Inserting director res: godzilla-mon
gnadmin-fd: message.c:259-0 Copy message resource 809be70 to 8099a68
gnadmin-fd: jcr.c:135-0 read_last_jobs seek to 188
gnadmin-fd: jcr.c:142-0 Read num_items=0
gnadmin-fd: pythonlib.c:113-0 No script dir. prog=FDStartUp
gnadmin-fd: filed.c:225-0 filed: listening on port 9102
gnadmin-fd: bnet_server.c:96-0 Addresses host[ipv4:192.168.99.24:9102]
gnadmin-fd: bnet.c:667-0 who=client host=192.168.99.10 port=36387
gnadmin-fd: jcr.c:617-0 OnEntry JobStatus=gnadmin-fd: jcr.c:637-0  
OnExit JobStatus=C set=C
gnadmin-fd: find.c:81-0 init_find_files ff=809d668
gnadmin-fd: job.c:233-0 <dird: Hello Director godzilla-dir calling
gnadmin-fd: job.c:249-0 Executing Hello command.
gnadmin-fd: job.c:359-0 Calling Authenticate
gnadmin-fd: cram-md5.c:73-0 send: auth cram-md5  
<192979336.1263482411@gnadmin-fd> ssl=0
gnadmin-fd: cram-md5.c:133-0 cram-get received: auth cram-md5  
<402525749.1263482434@godzilla-dir> ssl=0

===> Watch this:
gnadmin-fd: cram-md5.c:152-0 sending resp to challenge: iHEfd7ZmW7/ 
zg8two9ItMC
gnadmin-fd: cram-md5.c:154-0 Receive chanllenge response failed.  
ERR=Success

====> and back to where we were:
gnadmin-fd: authenticate.c:126-0 cram_get_auth failed for 192.168.99.10
gnadmin-fd: job.c:252-0 Quit command loop. Canceled=0
gnadmin-fd: runscript.c:103-0 runscript: running all RUNSCRIPT object  
(ClientAfterJob) JobStatus=C
gnadmin-fd: pythonlib.c:237-0 No startup module.
gnadmin-fd: job.c:343-0 Calling term_find_files
gnadmin-fd: job.c:346-0 Done with term_find_files
gnadmin-fd: mem_pool.c:377-0 garbage collect memory pool
gnadmin-fd: job.c:348-0 Done with free_jcr
- ---

Correspondant strace to above:

$> strace -i -T -v -f -F -p 27439
Process 27441 attached with 3 threads - interrupt to quit
[pid 27440] [b7f27410] select(5, [4], NULL, NULL, {141, 340000}  
<unfinished ...>
[pid 27439] [b7f27410] select(4, [3], NULL, NULL, NULL <unfinished ...>
[pid 27441] [b7f27410] clock_gettime(CLOCK_REALTIME, {1263482451,  
16266248}) = 0 <0.000019>
[pid 27441] [b7f27410] futex(0x80974e4, FUTEX_WAIT, 1, {80,  
715377752}) = -1 ETIMEDOUT (Connection timed out) <80.712073>
[pid 27441] [b7f27410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000051>
[pid 27441] [b7f27410] time(NULL)       = 1263482531 <0.000037>
[pid 27441] [b7f27410] gettimeofday({1263482531, 728932}, {4294967236,  
0}) = 0 <0.000047>
[pid 27441] [b7f27410] time(NULL)       = 1263482531 <0.000046>
[pid 27441] [b7f27410] clock_gettime(CLOCK_REALTIME, {1263482531,  
729180922}) = 0 <0.000049>
[pid 27441] [b7f27410] futex(0x80974e4, FUTEX_WAIT, 3, {119,  
999751078} <unfinished ...>
[pid 27440] [b7f27410] <... select resumed> ) = 0 (Timeout) <141.336600>
[pid 27440] [b7f27410] open("/usr/share/locale/locale.alias",  
O_RDONLY) = 5 <0.000067>
[pid 27440] [b7f27410] fstat64(5, {st_dev=makedev(9, 1),  
st_ino=134410, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=2582,  
st_atime=2010/01/14-16:20:24, st_mtime=2009/01/04-03:48:52,  
st_ctime=2009/12/14-12:01:19}) = 0 <0.000042>
[pid 27440] [b7f27410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f24000 <0.000051>
[pid 27440] [b7f27410] read(5, "# Locale name alias data base.\n#"...,  
4096) = 2582 <0.000070>
[pid 27440] [b7f27410] read(5, "", 4096) = 0 <0.000051>
[pid 27440] [b7f27410] close(5)         = 0 <0.000051>
[pid 27440] [b7f27410] munmap(0xb7f24000, 4096) = 0 <0.000055>
[pid 27440] [b7f27410] open("/usr/share/locale/en_US/LC_MESSAGES/ 
libc.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000060>
[pid 27440] [b7f27410] open("/usr/share/locale/en/LC_MESSAGES/ 
libc.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000056>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: cram-md5.c:154-0 Rec"...,  
77) = 77 <0.000064>
[pid 27440] [b7f27410] nanosleep({5, 0}, NULL) = 0 <5.009775>
[pid 27440] [b7f27410] getpeername(4, {sa_family=AF_INET,  
sin_port=htons(32910), sin_addr=inet_addr("192.168.99.10")}, [16]) = 0  
<0.000054>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: authenticate.c:126-0"...,  
71) = 71 <0.000069>
[pid 27440] [b7f27410] open("/usr/share/locale/en_US/LC_MESSAGES/ 
bacula.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000081>
[pid 27440] [b7f27410] open("/usr/share/locale/en/LC_MESSAGES/ 
bacula.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000059>
[pid 27440] [b7f27410] time(NULL)       = 1263482597 <0.000044>
[pid 27440] [b7f27410] open("/etc/localtime", O_RDONLY) = 5 <0.000057>
[pid 27440] [b7f27410] fstat64(5, {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:23:12, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000048>
[pid 27440] [b7f27410] fstat64(5, {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:23:12, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000040>
[pid 27440] [b7f27410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f24000 <0.000049>
[pid 27440] [b7f27410] read(5, "TZif 
\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\4\0\0\0\4\0"..., 4096) = 685  
<0.000084>
[pid 27440] [b7f27410] close(5)         = 0 <0.000043>
[pid 27440] [b7f27410] munmap(0xb7f24000, 4096) = 0 <0.000054>
[pid 27440] [b7f27410] stat64("/etc/localtime", {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:23:17, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000057>
[pid 27440] [b7f27410] futex(0x80974e4, FUTEX_WAKE, 1 <unfinished ...>
[pid 27441] [b7f27410] <... futex resumed> ) = 0 <65.627898>
[pid 27440] [b7f27410] <... futex resumed> ) = 1 <0.000094>
[pid 27440] [b7f27410] nanosleep({0, 100000},  <unfinished ...>
[pid 27441] [b7f27410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000062>
[pid 27441] [b7f27410] time(NULL)       = 1263482597 <0.000043>
[pid 27441] [b7f27410] gettimeofday({1263482597, 357637}, {4294967236,  
0}) = 0 <0.000043>
[pid 27441] [b7f27410] time(NULL)       = 1263482597 <0.000042>
[pid 27441] [b7f27410] clock_gettime(CLOCK_REALTIME, {1263482597,  
357866891}) = 0 <0.000043>
[pid 27441] [b7f27410] futex(0x80974e4, FUTEX_WAIT, 5, {119,  
999770109} <unfinished ...>
[pid 27440] [b7f27410] <... nanosleep resumed> NULL) = 0 <0.009352>
[pid 27440] [b7f27410] nanosleep({6, 0}, NULL) = 0 <6.007738>
[pid 27440] [b7f27410] write(4, "\0\0\0\v2999 No go\n", 15) = 15  
<0.000058>
[pid 27440] [b7f27410] time(NULL)       = 1263482603 <0.000056>
[pid 27440] [b7f27410] stat64("/etc/localtime", {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:23:17, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000074>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: job.c:252-0 Quit com"...,  
54) = 54 <0.000058>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: runscript.c:103-0 ru"...,  
99) = 99 <0.000064>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: pythonlib.c:237-0 No"...,  
49) = 49 <0.000061>
[pid 27440] [b7f27410] write(4, "\377\377\377\374", 4) = 4 <0.000057>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: job.c:343-0 Calling "...,  
48) = 48 <0.000060>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: job.c:346-0 Done wit"...,  
50) = 50 <0.000058>
[pid 27440] [b7f27410] close(4)         = 0 <0.000071>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: mem_pool.c:377-0 gar"...,  
57) = 57 <0.000060>
[pid 27440] [b7f27410] time(NULL)       = 1263482603 <0.000041>
[pid 27440] [b7f27410] write(1, "gnadmin-fd: job.c:348-0 Done wit"...,  
43) = 43 <0.000059>
[pid 27440] [b7f27410] gettimeofday({1263482603, 376972}, {4294967236,  
0}) = 0 <0.000045>
[pid 27440] [b7f27410] clock_gettime(CLOCK_REALTIME, {1263482603,  
377108298}) = 0 <0.000049>
[pid 27440] [b7f27410] futex(0x80976bc, FUTEX_WAIT, 1, {1, 622891702})  
= -1 ETIMEDOUT (Connection timed out) <1.629223>
[pid 27440] [b7f27410] futex(0x80976a0, FUTEX_WAKE, 1) = 0 <0.000041>
[pid 27440] [b7dd9299] _exit(0)         = ?
Process 27440 detached
[pid 27439] [b7f27410] <... select resumed> ) = 1 (in [3]) <160.491778>
[pid 27439] [b7f27410] accept(3, {sa_family=AF_INET,  
sin_port=htons(32911), sin_addr=inet_addr("192.168.99.10")}, [16]) = 4  
<0.000066>
[pid 27439] [b7f27410] getpeername(4, {sa_family=AF_INET,  
sin_port=htons(32911), sin_addr=inet_addr("192.168.99.10")}, [16]) = 0  
<0.000045>
[pid 27439] [b7f27410] getsockname(4, {sa_family=AF_INET,  
sin_port=htons(9102), sin_addr=inet_addr("192.168.99.24")}, [16]) = 0  
<0.000039>
[pid 27439] [b7f27410] open("/etc/hosts.allow", O_RDONLY) = 5 <0.000058>
[pid 27439] [b7f27410] fstat64(5, {st_dev=makedev(9, 1),  
st_ino=928703, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=773,  
st_atime=2010/01/14-16:20:11, st_mtime=2010/01/14-11:31:20,  
st_ctime=2010/01/14-11:31:20}) = 0 <0.000045>
[pid 27439] [b7f27410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f24000 <0.000048>
[pid 27439] [b7f27410] read(5, "# /etc/hosts.allow: list of host"...,  
4096) = 773 <0.000093>
[pid 27439] [b7f27410] close(5)         = 0 <0.000054>
[pid 27439] [b7f27410] munmap(0xb7f24000, 4096) = 0 <0.000066>
[pid 27439] [b7f27410] setsockopt(4, SOL_SOCKET, SO_KEEPALIVE, [1], 4)  
= 0 <0.000051>
[pid 27439] [b7f27410] write(1, "gnadmin-fd: bnet.c:667-0 who=cli"...,  
65) = 65 <0.000063>
[pid 27439] [b7c4c488] clone(Process 27446 attached
child_stack=0xb79c74c4, flags=CLONE_VM|CLONE_FS|CLONE_FILES| 
CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS| 
CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED,  
parent_tidptr=0xb79c7bf8, {entry_number:6, base_addr:0xb79c7bb0, limit: 
1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,  
seg_not_present:0, useable:1}, child_tidptr=0xb79c7bf8) = 27446  
<0.000078>
[pid 27446] [b7f27410] futex(0x80976a0, FUTEX_WAIT, 2, NULL  
<unfinished ...>
[pid 27439] [b7f27410] futex(0x80976a0, FUTEX_WAKE, 1) = 1 <0.000061>
[pid 27446] [b7f27410] <... futex resumed> ) = 0 <0.000166>
[pid 27446] [b7f27410] gettimeofday({1263482611, 500501}, {4294967236,  
0}) = 0 <0.000012>
[pid 27446] [b7f27410] futex(0x80976a0, FUTEX_WAKE, 1) = 0 <0.000012>
[pid 27446] [b7f27410] time(NULL)       = 1263482611 <0.000013>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: jcr.c:617-0 OnEntry "...,  
42 <unfinished ...>
[pid 27439] [b7f27410] select(4, [3], NULL, NULL, NULL <unfinished ...>
[pid 27446] [b7f27410] <... write resumed> ) = 42 <0.000074>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: jcr.c:637-0 OnExit J"...,  
49) = 49 <0.000019>
[pid 27446] [b7f27410] rt_sigaction(SIGUSR2, {0x806fec0, ~[RTMIN  
RT_1], 0}, NULL, 8) = 0 <0.000015>
[pid 27446] [b7f27410] statfs(".", {f_type="EXT2_SUPER_MAGIC",  
f_bsize=4096, f_blocks=2403513, f_bfree=2128733, f_bavail=2006641,  
f_files=1221600, f_ffree=1160663, f_fsid={0, 0}, f_namelen=255,  
f_frsize=4096}) = 0 <0.000036>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: find.c:81-0 init_fin"...,  
51) = 51 <0.000065>
[pid 27446] [b7f27410] time(NULL)       = 1263482611 <0.000050>
[pid 27446] [b7f27410] read(4, "\0\0\0$", 4) = 4 <0.011714>
[pid 27446] [b7f27410] read(4, "Hello Director godzilla-dir call"...,  
36) = 36 <0.000043>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: job.c:233-0 <dird: H"...,  
67) = 67 <0.000059>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: job.c:249-0 Executin"...,  
49) = 49 <0.000059>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: job.c:359-0 Calling "...,  
45) = 45 <0.000051>
[pid 27446] [b7f27410] futex(0x80974e4, FUTEX_WAKE, 1 <unfinished ...>
[pid 27441] [b7f27410] <... futex resumed> ) = 0 <14.156056>
[pid 27446] [b7f27410] <... futex resumed> ) = 1 <0.000089>
[pid 27441] [b7f27410] futex(0x80974e0, FUTEX_WAIT, 2, NULL  
<unfinished ...>
[pid 27446] [b7f27410] futex(0x80974e0, FUTEX_WAKE, 1 <unfinished ...>
[pid 27441] [b7f27410] <... futex resumed> ) = 0 <0.000131>
[pid 27446] [b7f27410] <... futex resumed> ) = 1 <0.000094>
[pid 27441] [b7f27410] futex(0x80974e0, FUTEX_WAKE, 1) = 0 <0.000012>
[pid 27441] [b7f27410] futex(0x809818c, FUTEX_WAIT, 2, NULL  
<unfinished ...>
[pid 27446] [b7f27410] futex(0x809818c, FUTEX_WAKE, 1 <unfinished ...>
[pid 27441] [b7f27410] <... futex resumed> ) = 0 <0.000126>
[pid 27446] [b7f27410] <... futex resumed> ) = 1 <0.000074>
[pid 27441] [b7f27410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000014>
[pid 27441] [b7f27410] time(NULL)       = 1263482611 <0.000013>
[pid 27441] [b7f27410] gettimeofday({1263482611, 514667}, {4294967236,  
0}) = 0 <0.000012>
[pid 27441] [b7f27410] time(NULL)       = 1263482611 <0.000013>
[pid 27441] [b7f27410] clock_gettime(CLOCK_REALTIME, {1263482611,  
514788156}) = 0 <0.000015>
[pid 27441] [b7f27410] futex(0x80974e4, FUTEX_WAIT, 7, {119,  
999878844} <unfinished ...>
[pid 27446] [b7f27410] nanosleep({0, 100000}, NULL) = 0 <0.007296>
[pid 27446] [b7f27410] gettimeofday({1263482611, 522338}, {4294967236,  
0}) = 0 <0.000040>
[pid 27446] [b7f27410] gettimeofday({1263482611, 522463}, {4294967236,  
0}) = 0 <0.000046>
[pid 27446] [b7f27410] gettimeofday({1263482611, 522588}, {4294967236,  
0}) = 0 <0.000048>
[pid 27446] [b7f27410] gettimeofday({1263482611, 522716}, {4294967236,  
0}) = 0 <0.000050>
[pid 27446] [b7f27410] gettimeofday({1263482611, 522841}, {4294967236,  
0}) = 0 <0.000047>
[pid 27446] [b7f27410] uname({sysname="Linux", nodename="admin",  
release="2.6.18-6-686", version="#1 SMP Thu Nov 5 16:28:13 UTC 2009",  
machine="i686"}) = 0 <0.000047>
[pid 27446] [b7f27410] time(NULL)       = 1263482611 <0.000039>
[pid 27446] [b7f27410] write(1, "gnadmin-fd: cram-md5.c:73-0 send"...,  
88) = 88 <0.000062>
[pid 27446] [b7f27410] write(4, "\0\0\0006auth cram-md5  
<244286855.126"..., 58) = 58 <0.000062>
- ----
And here we end with the corresponding strace...



> I think that is only showing the main thread.  Try adding -f to the  
> strace
> command line.

...whereas a completely "unsuccsessful" strace looks like this
(like initially reported) strace looks like this (bacula-fd identical  
to original post):

$> strace -i -T -v -f -F -p 27474

Process 27474 attached - interrupt to quit
[b7efe410] select(5, [4], NULL, NULL, NULL) = 1 (in [4]) <16.170810>
[b7efe410] accept(4, {sa_family=AF_INET, sin_port=htons(32772),  
sin_addr=inet_addr("192.168.99.10")}, [16]) = 5 <0.000035>
[b7efe410] getpeername(5, {sa_family=AF_INET, sin_port=htons(32772),  
sin_addr=inet_addr("192.168.99.10")}, [16]) = 0 <0.000047>
[b7efe410] getsockname(5, {sa_family=AF_INET, sin_port=htons(9102),  
sin_addr=inet_addr("192.168.99.24")}, [16]) = 0 <0.000042>
[b7efe410] open("/etc/hosts.allow", O_RDONLY) = 6 <0.000062>
[b7efe410] fstat64(6, {st_dev=makedev(9, 1), st_ino=928703,  
st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0, st_blksize=4096,  
st_blocks=8, st_size=773, st_atime=2010/01/14-16:35:49,  
st_mtime=2010/01/14-11:31:20, st_ctime=2010/01/14-11:31:20}) = 0  
<0.000044>
[b7efe410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE| 
MAP_ANONYMOUS, -1, 0) = 0xb7efb000 <0.000045>
[b7efe410] read(6, "# /etc/hosts.allow: list of host"..., 4096) = 773  
<0.000076>
[b7efe410] close(6)                     = 0 <0.000053>
[b7efe410] munmap(0xb7efb000, 4096)     = 0 <0.000052>
[b7efe410] setsockopt(5, SOL_SOCKET, SO_KEEPALIVE, [1], 4) = 0  
<0.000055>
[b7efe410] write(1, "gnadmin-fd: bnet.c:667-0 who=cli"..., 65) = 65  
<0.000068>
[b7efe410] mmap2(NULL, 8392704, PROT_READ|PROT_WRITE, MAP_PRIVATE| 
MAP_ANONYMOUS, -1, 0) = 0xb719e000 <0.000063>
[b7efe410] mprotect(0xb719e000, 4096, PROT_NONE) = 0 <0.000056>
[b7c23488] clone(Process 27503 attached
child_stack=0xb799e4c4, flags=CLONE_VM|CLONE_FS|CLONE_FILES| 
CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS| 
CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED,  
parent_tidptr=0xb799ebf8, {entry_number:6, base_addr:0xb799ebb0, limit: 
1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,  
seg_not_present:0, useable:1}, child_tidptr=0xb799ebf8) = 27503  
<0.000057>
[pid 27503] [b7efe410] futex(0x80976a0, FUTEX_WAIT, 2, NULL  
<unfinished ...>
[pid 27474] [b7efe410] futex(0x80976a0, FUTEX_WAKE, 1 <unfinished ...>
[pid 27503] [b7efe410] <... futex resumed> ) = -1 EAGAIN (Resource  
temporarily unavailable) <0.000049>
[pid 27474] [b7efe410] <... futex resumed> ) = 0 <0.000072>
[pid 27474] [b7efe410] select(5, [4], NULL, NULL, NULL <unfinished ...>
[pid 27503] [b7efe410] gettimeofday({1263483542, 767896}, {4294967236,  
0}) = 0 <0.000013>
[pid 27503] [b7efe410] futex(0x80976a0, FUTEX_WAKE, 1) = 0 <0.000015>
[pid 27503] [b7efe410] time(NULL)       = 1263483542 <0.000012>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: jcr.c:617-0 OnEntry "...,  
42) = 42 <0.000022>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: jcr.c:637-0 OnExit J"...,  
49) = 49 <0.000019>
[pid 27503] [b7efe410] rt_sigaction(SIGUSR2, {0x806fec0, ~[RTMIN  
RT_1], 0}, NULL, 8) = 0 <0.000015>
[pid 27503] [b7efe410] statfs(".", {f_type="EXT2_SUPER_MAGIC",  
f_bsize=4096, f_blocks=2403513, f_bfree=2128733, f_bavail=2006641,  
f_files=1221600, f_ffree=1160663, f_fsid={0, 0}, f_namelen=255,  
f_frsize=4096}) = 0 <0.000031>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: find.c:81-0 init_fin"...,  
51) = 51 <0.000021>
[pid 27503] [b7efe410] time(NULL)       = 1263483542 <0.000012>
[pid 27503] [b7efe410] read(5, "\0\0\0$", 4) = 4 <0.007062>
[pid 27503] [b7efe410] read(5, "Hello Director godzilla-dir call"...,  
36) = 36 <0.000044>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:233-0 <dird: H"...,  
67) = 67 <0.000062>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:249-0 Executin"...,  
49) = 49 <0.000065>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:359-0 Calling "...,  
45) = 45 <0.000056>
[pid 27503] [b7efe410] time(NULL)       = 1263483542 <0.000041>
[pid 27503] [b7efe410] mmap2(NULL, 8392704, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb699d000 <0.000055>
[pid 27503] [b7efe410] mprotect(0xb699d000, 4096, PROT_NONE) = 0  
<0.000067>
[pid 27503] [b7c23488] clone(Process 27504 attached
child_stack=0xb719d4c4, flags=CLONE_VM|CLONE_FS|CLONE_FILES| 
CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS| 
CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID|CLONE_DETACHED,  
parent_tidptr=0xb719dbf8, {entry_number:6, base_addr:0xb719dbb0, limit: 
1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,  
seg_not_present:0, useable:1}, child_tidptr=0xb719dbf8) = 27504  
<0.000057>
[pid 27504] [b7efe410] time(NULL)       = 1263483542 <0.000012>
[pid 27504] [b7efe410] gettimeofday({1263483542, 777140}, {4294967236,  
0}) = 0 <0.000013>
[pid 27504] [b7efe410] time(NULL)       = 1263483542 <0.000012>
[pid 27504] [b7efe410] clock_gettime(CLOCK_REALTIME, {1263483542,  
777270561}) = 0 <0.000014>
[pid 27504] [b7efe410] futex(0x80974e4, FUTEX_WAIT, 1, {119,  
999869439} <unfinished ...>
[pid 27503] [b7efe410] futex(0x80974e4, FUTEX_WAKE, 1 <unfinished ...>
[pid 27504] [b7efe410] <... futex resumed> ) = 0 <0.000128>
[pid 27503] [b7efe410] <... futex resumed> ) = 1 <0.000085>
[pid 27503] [b7efe410] nanosleep({0, 100000},  <unfinished ...>
[pid 27504] [b7efe410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000063>
[pid 27504] [b7efe410] time(NULL)       = 1263483542 <0.000047>
[pid 27504] [b7efe410] gettimeofday({1263483542, 777918}, {4294967236,  
0}) = 0 <0.000043>
[pid 27504] [b7efe410] time(NULL)       = 1263483542 <0.000042>
[pid 27504] [b7efe410] clock_gettime(CLOCK_REALTIME, {1263483542,  
778143350}) = 0 <0.000044>
[pid 27504] [b7efe410] futex(0x80974e4, FUTEX_WAIT, 3, {119,  
999774650} <unfinished ...>
[pid 27503] [b7efe410] <... nanosleep resumed> NULL) = 0 <0.007243>
[pid 27503] [b7efe410] gettimeofday({1263483542, 784951}, {4294967236,  
0}) = 0 <0.000041>
[pid 27503] [b7efe410] gettimeofday({1263483542, 785076}, {4294967236,  
0}) = 0 <0.000056>
[pid 27503] [b7efe410] gettimeofday({1263483542, 785202}, {4294967236,  
0}) = 0 <0.000040>
[pid 27503] [b7efe410] gettimeofday({1263483542, 785326}, {4294967236,  
0}) = 0 <0.000048>
[pid 27503] [b7efe410] gettimeofday({1263483542, 785450}, {4294967236,  
0}) = 0 <0.000047>
[pid 27503] [b7efe410] uname({sysname="Linux", nodename="admin",  
release="2.6.18-6-686", version="#1 SMP Thu Nov 5 16:28:13 UTC 2009",  
machine="i686"}) = 0 <0.000047>
[pid 27503] [b7efe410] time(NULL)       = 1263483542 <0.000040>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: cram-md5.c:73-0 send"...,  
88) = 88 <0.000075>
[pid 27503] [b7efe410] write(5, "\0\0\0006auth cram-md5  
<199816554.126"..., 58) = 58 <0.000063>
[pid 27503] [b7efe410] select(6, [5], NULL, NULL, {180, 0}  
<unfinished ...>
[pid 27504] [b7efe410] <... futex resumed> ) = -1 ETIMEDOUT  
(Connection timed out) <119.995325>
[pid 27504] [b7efe410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000047>
[pid 27504] [b7efe410] time(NULL)       = 1263483662 <0.000048>
[pid 27504] [b7efe410] gettimeofday({1263483662, 774047}, {4294967236,  
0}) = 0 <0.000038>
[pid 27504] [b7efe410] time(NULL)       = 1263483662 <0.000044>
[pid 27504] [b7efe410] clock_gettime(CLOCK_REALTIME, {1263483662,  
774288893}) = 0 <0.000050>
[pid 27504] [b7efe410] futex(0x80974e4, FUTEX_WAIT, 5, {119,  
999758107} <unfinished ...>
[pid 27503] [b7efe410] <... select resumed> ) = 0 (Timeout) <179.993577>
[pid 27503] [b7efe410] open("/usr/share/locale/locale.alias",  
O_RDONLY) = 6 <0.000066>
[pid 27503] [b7efe410] fstat64(6, {st_dev=makedev(9, 1),  
st_ino=134410, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=2582,  
st_atime=2010/01/14-16:38:41, st_mtime=2009/01/04-03:48:52,  
st_ctime=2009/12/14-12:01:19}) = 0 <0.000042>
[pid 27503] [b7efe410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7efb000 <0.000051>
[pid 27503] [b7efe410] read(6, "# Locale name alias data base.\n#"...,  
4096) = 2582 <0.000094>
[pid 27503] [b7efe410] read(6, "", 4096) = 0 <0.000052>
[pid 27503] [b7efe410] close(6)         = 0 <0.000058>
[pid 27503] [b7efe410] munmap(0xb7efb000, 4096) = 0 <0.000063>
[pid 27503] [b7efe410] open("/usr/share/locale/en_US/LC_MESSAGES/ 
libc.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000062>
[pid 27503] [b7efe410] open("/usr/share/locale/en/LC_MESSAGES/ 
libc.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000057>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: cram-md5.c:89-0 Bnet"...,  
67) = 67 <0.000065>
[pid 27503] [b7efe410] nanosleep({5, 0}, NULL) = 0 <5.005940>
[pid 27503] [b7efe410] getpeername(5, {sa_family=AF_INET,  
sin_port=htons(32772), sin_addr=inet_addr("192.168.99.10")}, [16]) = 0  
<0.000052>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: authenticate.c:131-0"...,  
67) = 67 <0.000070>
[pid 27503] [b7efe410] open("/usr/share/locale/en_US/LC_MESSAGES/ 
bacula.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000071>
[pid 27503] [b7efe410] open("/usr/share/locale/en/LC_MESSAGES/ 
bacula.mo", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000051>
[pid 27503] [b7efe410] time(NULL)       = 1263483727 <0.000048>
[pid 27503] [b7efe410] open("/etc/localtime", O_RDONLY) = 6 <0.000060>
[pid 27503] [b7efe410] fstat64(6, {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:39:02, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000049>
[pid 27503] [b7efe410] fstat64(6, {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:39:02, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000068>
[pid 27503] [b7efe410] mmap2(NULL, 4096, PROT_READ|PROT_WRITE,  
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7efb000 <0.000048>
[pid 27503] [b7efe410] read(6, "TZif 
\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\4\0\0\0\4\0"..., 4096) = 685  
<0.000087>
[pid 27503] [b7efe410] close(6)         = 0 <0.000043>
[pid 27503] [b7efe410] munmap(0xb7efb000, 4096) = 0 <0.000060>
[pid 27503] [b7efe410] stat64("/etc/localtime", {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:42:07, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000061>
[pid 27503] [b7efe410] futex(0x80974e4, FUTEX_WAKE, 1) = 1 <0.000097>
[pid 27503] [b7efe410] nanosleep({0, 100000},  <unfinished ...>
[pid 27504] [b7efe410] <... futex resumed> ) = 0 <65.016096>
[pid 27504] [b7efe410] futex(0x809818c, FUTEX_WAKE, 1) = 0 <0.000013>
[pid 27504] [b7efe410] time(NULL)       = 1263483727 <0.000012>
[pid 27504] [b7efe410] gettimeofday({1263483727, 790646}, {4294967236,  
0}) = 0 <0.000013>
[pid 27504] [b7efe410] time(NULL)       = 1263483727 <0.000012>
[pid 27504] [b7efe410] clock_gettime(CLOCK_REALTIME, {1263483727,  
790762249}) = 0 <0.000014>
[pid 27504] [b7efe410] futex(0x80974e4, FUTEX_WAIT, 7, {119,  
999883751} <unfinished ...>
[pid 27503] [b7efe410] <... nanosleep resumed> NULL) = 0 <0.005294>
[pid 27503] [b7efe410] nanosleep({6, 0}, NULL) = 0 <6.007725>
[pid 27503] [b7efe410] write(5, "\0\0\0\v2999 No go\n", 15) = 15  
<0.000061>
[pid 27503] [b7efe410] time(NULL)       = 1263483733 <0.000062>
[pid 27503] [b7efe410] stat64("/etc/localtime", {st_dev=makedev(9, 1),  
st_ino=928521, st_mode=S_IFREG|0644, st_nlink=1, st_uid=0, st_gid=0,  
st_blksize=4096, st_blocks=8, st_size=685,  
st_atime=2010/01/14-16:42:07, st_mtime=2009/12/14-12:01:22,  
st_ctime=2009/12/14-12:01:22}) = 0 <0.000073>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:252-0 Quit com"...,  
54) = 54 <0.000061>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: runscript.c:103-0 ru"...,  
99) = 99 <0.000072>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: pythonlib.c:237-0 No"...,  
49) = 49 <0.000063>
[pid 27503] [b7efe410] write(5, "\377\377\377\374", 4) = -1 ECONNRESET  
(Connection reset by peer) <0.000055>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:343-0 Calling "...,  
48) = 48 <0.000061>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:346-0 Done wit"...,  
50) = 50 <0.000060>
[pid 27503] [b7efe410] close(5)         = 0 <0.000068>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: mem_pool.c:377-0 gar"...,  
57) = 57 <0.000059>
[pid 27503] [b7efe410] time(NULL)       = 1263483733 <0.000041>
[pid 27503] [b7efe410] write(1, "gnadmin-fd: job.c:348-0 Done wit"...,  
43) = 43 <0.000058>
[pid 27503] [b7efe410] gettimeofday({1263483733, 806042}, {4294967236,  
0}) = 0 <0.000042>
[pid 27503] [b7efe410] clock_gettime(CLOCK_REALTIME, {1263483733,  
806168645}) = 0 <0.000047>
[pid 27503] [b7efe410] futex(0x80976bc, FUTEX_WAIT, 1, {1, 193831355})  
= -1 ETIMEDOUT (Connection timed out) <1.201302>
[pid 27503] [b7efe410] futex(0x80976a0, FUTEX_WAKE, 1) = 0 <0.000044>
[pid 27503] [b7db0299] _exit(0)         = ?

- ----

Rgds

Simon



-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.8 (Darwin)

iEYEARECAAYFAktPPJwACgkQFMrSEgQnfr3YJACfd69hT1aP2U3OnkL4bdkzAdRT
m/IAnRfm7sVjmmJTykJK11ETbvWm3Iz/
=MMQG
-----END PGP SIGNATURE-----

------------------------------------------------------------------------------
Throughout its 18-year history, RSA Conference consistently attracts the
world's best and brightest in the field, creating opportunities for Conference
attendees to learn about information security's most important issues through
interactions with peers, luminaries and emerging and established companies.
http://p.sf.net/sfu/rsaconf-dev2dev
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users