[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

Re: [Bacula-devel] Multiple fd plugins in one fileset: Restore problems


Hello Kern,

On Monday 29 September 2008, Kern Sibbald wrote:
> I just committed a minor but important change to fd_plugins.c that I hope
> will fix the problem you ran into.

Thx for this...

> Please try again, and if it dies, please send me another gdb traceback as
> you did previously.

... but unfortunately, both problems (not restoring all data, and the crash) 
persist.

I am attaching two log files again, this time I used -d 150, and a new 
backtrace.

Thx
   Bastian

-- 
Collax GmbH . Burkheimer Straße 3 . 79111 Freiburg . Germany
p: +49 (0) 761-45684-24
f: +49 (0) 761-45684-10        www.collax.com

Geschäftsführer: William K. Hite / Boris Nalbach
AG München HRB 158898 . Ust.-IdNr: DE 814464942
\ What has four legs and an arm? A happy pitbull.
bacula-fd: filed_conf.c:444-0 Inserting director res: bilbobox4-dir
bacula-fd: filed_conf.c:444-0 Inserting director res: apollo-dir
bilbobox4-fd: jcr.c:141-0 read_last_jobs seek to 188
bilbobox4-fd: jcr.c:148-0 Read num_items=10
bilbobox4-fd: plugins.c:113-0 Loaded plugin: name=bpipe-fd.so len=11
bilbobox4-fd: fd_plugins.c:418-0 Loaded plugin: bpipe-fd.so
bilbobox4-fd: pythonlib.c:113-0 No script dir. prog=FDStartUp
bilbobox4-fd: filed.c:234-0 filed: listening on port 9102
bilbobox4-fd: bnet_server.c:96-0 Addresses host[ipv4:0.0.0.0:9102] 
bilbobox4-fd: bnet.c:669-0 who=client host=172.16.1.204 port=36387
bilbobox4-fd: find.c:81-0 init_find_files ff=9854770
bilbobox4-fd: job.c:234-0 <dird: Hello Director bilbobox4-dir calling
bilbobox4-fd: job.c:250-0 Executing Hello command.
bilbobox4-fd: job.c:372-0 Calling Authenticate
bilbobox4-fd: cram-md5.c:73-0 send: auth cram-md5 <415320110.1222760824@bilbobox4-fd> ssl=0
bilbobox4-fd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1963318919.1222760824@bilbobox4-dir> ssl=0
bilbobox4-fd: cram-md5.c:152-0 sending resp to challenge: FkllW9+LQj/ufy+2B8UgZA
bilbobox4-fd: job.c:376-0 OK Authenticate
bilbobox4-fd: job.c:234-0 <dird: JobId=2531 Job=RestoreFiles.2008-09-30_09.47.10 SDid=4 SDtime=1222753667 Authorization=HFOB-OOKE-COOF-LELN-AENA-CIAA-CPAB-IMOC
bilbobox4-fd: job.c:250-0 Executing JobId= command.
bilbobox4-fd: job.c:471-2531 JobId=2531 Auth=HFOB-OOKE-COOF-LELN-AENA-CIAA-CPAB-IMOC
bilbobox4-fd: fd_plugins.c:448-2531 Instantiate plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: fd_plugins.c:104-2531 plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: job.c:234-2531 <dird: storage address=bilbobox4.coreworks.de port=9103 ssl=0
bilbobox4-fd: job.c:250-2531 Executing storage  command.
bilbobox4-fd: job.c:1365-2531 StorageCmd: storage address=bilbobox4.coreworks.de port=9103 ssl=0
bilbobox4-fd: job.c:1371-2531 Open storage: bilbobox4.coreworks.de:9103 ssl=0
bilbobox4-fd: bsock.c:201-2531 Current host[ipv4:172.16.1.204:9103] All host[ipv4:172.16.1.204:9103] 
bilbobox4-fd: bsock.c:155-2531 who=Storage daemon host=bilbobox4.coreworks.de port=9103
bilbobox4-fd: job.c:1383-2531 Connection OK to SD.
bilbobox4-fd: cram-md5.c:133-2531 cram-get received: auth cram-md5 <349426926.1222760824@bilbobox4-sd> ssl=0
bilbobox4-fd: cram-md5.c:152-2531 sending resp to challenge: xy+Im6/AT5//T5/waA/mVA
bilbobox4-fd: cram-md5.c:80-2531 send: auth cram-md5 <1511074893.1222760824@bilbobox4-fd> ssl=0
bilbobox4-fd: cram-md5.c:99-2531 Authenticate OK M6QppA9+5/Igy7Rz35+JhD
bilbobox4-fd: job.c:1392-2531 Authenticated with SD.
bilbobox4-fd: job.c:234-2531 <dird: bootstrap
bilbobox4-fd: job.c:250-2531 Executing bootstrap command.
bilbobox4-fd: job.c:234-2531 <dird: restore replace=a prelinks=0 where=/
bilbobox4-fd: job.c:250-2531 Executing restore command.
bilbobox4-fd: job.c:1678-2531 restore command
bilbobox4-fd: job.c:1699-2531 Got replace a, where=
bilbobox4-fd: job.c:1718-2531 bfiled>dird: 2000 OK restore
bilbobox4-fd: job.c:1776-2531 VolSessId=4 VolsessT=1222753667 SF=0 EF=0
bilbobox4-fd: job.c:1777-2531 JobId=2531 vol=DummyVolume
bilbobox4-fd: job.c:1784-2531 >stored: read open session = DummyVolume 4 1222753667 0 0 0 0
bilbobox4-fd: job.c:1790-2531 bfiled<stored: 3000 OK open ticket = 4
bilbobox4-fd: job.c:1795-2531 bfiled: got Ticket=4
bilbobox4-fd: job.c:1855-2531 3000 OK bootstrap
bilbobox4-fd: job.c:1809-2531 >stored: read data 4
bilbobox4-fd: job.c:1855-2531 3000 OK data
bilbobox4-fd: pythonlib.c:238-2531 No startup module.
bilbobox4-fd: fd_plugins.c:104-2531 plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=0 FilInx=2 Stream=26, 26.
bilbobox4-fd: restore.c:272-2531 Got stream: 26 len=73 extract=0
bilbobox4-fd: restore.c:667-2531 restore stream_plugin_name=2 1 1 bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bilbobox4-fd: fd_plugins.c:265-2531 Read plugin stream string=2 1 1 bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bilbobox4-fd: fd_plugins.c:292-2531 plugin restore cmd=bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bilbobox4-fd: fd_plugins.c:307-2531 plugin=bpipe-fd.so cmd=bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1 len=5
bilbobox4-fd: fd_plugins.c:312-2531 Restore Command plugin = bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bpipe-fd: EventRestoreCommand cmd=bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bpipe-fd: pluginEvent cmd=bpipe:myname1:/home/b/bastian/tmp05/out1:/home/b/bastian/tmp05/in1
bpipe-fd: plugin=bpipe fname=myname1 reader=/home/b/bastian/tmp05/out1 writer=/home/b/bastian/tmp05/in1
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=0 FilInx=2 Stream=1, Unix attributes.
bilbobox4-fd: restore.c:272-2531 Got stream: Unix attributes len=66 extract=0
bpipe-fd: IO_OPEN writer=/home/b/bastian/tmp05/in1
bilbobox4-fd: restore.c:373-2531 Outfile=myname1 create_file stat=3
bilbobox4-fd: attr.c:281-2531 -rwx------   0 root     root            -1 2008-09-30 08:49:05  myname1
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=1 FilInx=2 Stream=2, File data.
bilbobox4-fd: restore.c:272-2531 Got stream: File data len=14 extract=1
bilbobox4-fd: fd_plugins.c:563-2531 plugin_bwrite
bilbobox4-fd: restore.c:1117-2531 Write 14 bytes, JobBytes=14
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=1 FilInx=2 Stream=3, MD5 digest.
bilbobox4-fd: restore.c:272-2531 Got stream: MD5 digest len=16 extract=1
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=1 FilInx=3 Stream=26, 26.
bilbobox4-fd: restore.c:272-2531 Got stream: 26 len=3 extract=1
bilbobox4-fd: restore.c:667-2531 restore stream_plugin_name=0 0
bilbobox4-fd: fd_plugins.c:265-2531 Read plugin stream string=0 0
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=1 FilInx=3 Stream=26, 26.
bilbobox4-fd: restore.c:272-2531 Got stream: 26 len=73 extract=1
bilbobox4-fd: restore.c:667-2531 restore stream_plugin_name=3 1 1 bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bilbobox4-fd: fd_plugins.c:265-2531 Read plugin stream string=3 1 1 bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bilbobox4-fd: fd_plugins.c:292-2531 plugin restore cmd=bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bilbobox4-fd: fd_plugins.c:307-2531 plugin=bpipe-fd.so cmd=bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2 len=5
bilbobox4-fd: fd_plugins.c:312-2531 Restore Command plugin = bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bpipe-fd: EventRestoreCommand cmd=bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bpipe-fd: pluginEvent cmd=bpipe:myname2:/home/b/bastian/tmp05/out2:/home/b/bastian/tmp05/in2
bpipe-fd: plugin=bpipe fname=myname2 reader=/home/b/bastian/tmp05/out2 writer=/home/b/bastian/tmp05/in2
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=1 FilInx=3 Stream=1, Unix attributes.
bilbobox4-fd: restore.c:272-2531 Got stream: Unix attributes len=66 extract=1
bilbobox4-fd: restore.c:317-2531 Stop extracting.
bpipe-fd: IO_OPEN writer=/home/b/bastian/tmp05/in2
bilbobox4-fd: restore.c:373-2531 Outfile=myname2 create_file stat=3
bilbobox4-fd: attr.c:281-2531 -rwx------   0 root     root            -1 2008-09-30 08:49:05  myname2
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=2 FilInx=3 Stream=2, File data.
bilbobox4-fd: restore.c:272-2531 Got stream: File data len=14 extract=1
bilbobox4-fd: fd_plugins.c:563-2531 plugin_bwrite
bilbobox4-fd: restore.c:1117-2531 Write 14 bytes, JobBytes=28
bilbobox4-fd: restore.c:259-2531 Got hdr: Files=2 FilInx=3 Stream=3, MD5 digest.
bilbobox4-fd: restore.c:272-2531 Got stream: MD5 digest len=16 extract=1
bilbobox4-fd: fd_plugins.c:513-2531 plugin_bclose
bilbobox4-fd: restore.c:775-2531 End Do Restore. Files=2 Bytes=28
bilbobox4-fd: job.c:1749-2531 bfiled>stored: read close session 4
bilbobox4-fd: job.c:1762-2531 Done in job.c
bilbobox4-fd: fd_plugins.c:104-2531 plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: job.c:253-2531 Quit command loop. Canceled=1
bilbobox4-fd: job.c:280-2531 End FD msg: 2800 End Job TermCode=69 JobFiles=2 ReadBytes=28 JobBytes=28 Errors=1 VSS=0 Encrypt=0

bilbobox4-fd: pythonlib.c:238-2531 No startup module.
bilbobox4-fd: fd_plugins.c:104-2531 plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: fd_plugins.c:470-2531 Free instance plugin_ctx=9854ea0 JobId=2531
bilbobox4-fd: job.c:356-2531 Calling term_find_files
bilbobox4-fd: job.c:359-2531 Done with term_find_files
bilbobox4-fd: jcr.c:184-2531 write_last_jobs seek to 188
bilbobox4-fd: job.c:361-0 Done with free_jcr
bacula-fd: filed_conf.c:444-0 Inserting director res: bilbobox4-dir
bacula-fd: filed_conf.c:444-0 Inserting director res: apollo-dir
bilbobox4-fd: jcr.c:141-0 read_last_jobs seek to 188
bilbobox4-fd: jcr.c:148-0 Read num_items=10
bilbobox4-fd: plugins.c:113-0 Loaded plugin: name=bpipe-fd.so len=11
bilbobox4-fd: fd_plugins.c:418-0 Loaded plugin: bpipe-fd.so
bilbobox4-fd: pythonlib.c:113-0 No script dir. prog=FDStartUp
bilbobox4-fd: filed.c:234-0 filed: listening on port 9102
bilbobox4-fd: bnet_server.c:96-0 Addresses host[ipv4:0.0.0.0:9102] 
bilbobox4-fd: bnet.c:669-0 who=client host=172.16.1.204 port=36387
bilbobox4-fd: find.c:81-0 init_find_files ff=9c08770
bilbobox4-fd: job.c:234-0 <dird: Hello Director bilbobox4-dir calling
bilbobox4-fd: job.c:250-0 Executing Hello command.
bilbobox4-fd: job.c:372-0 Calling Authenticate
bilbobox4-fd: cram-md5.c:73-0 send: auth cram-md5 <1765442170.1222760846@bilbobox4-fd> ssl=0
bilbobox4-fd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1982669052.1222760846@bilbobox4-dir> ssl=0
bilbobox4-fd: cram-md5.c:152-0 sending resp to challenge: g/+hBChVx1/Ij9MC/9+q9A
bilbobox4-fd: job.c:376-0 OK Authenticate
bilbobox4-fd: job.c:234-0 <dird: JobId=2532 Job=RestoreFiles.2008-09-30_09.47.13 SDid=5 SDtime=1222753667 Authorization=KKNL-AKMF-EMNM-FCNM-OLBB-CLOL-IGKN-OGFE
bilbobox4-fd: job.c:250-0 Executing JobId= command.
bilbobox4-fd: job.c:471-2532 JobId=2532 Auth=KKNL-AKMF-EMNM-FCNM-OLBB-CLOL-IGKN-OGFE
bilbobox4-fd: fd_plugins.c:448-2532 Instantiate plugin_ctx=9c08ea0 JobId=2532
bilbobox4-fd: fd_plugins.c:104-2532 plugin_ctx=9c08ea0 JobId=2532
bilbobox4-fd: job.c:234-2532 <dird: storage address=bilbobox4.coreworks.de port=9103 ssl=0
bilbobox4-fd: job.c:250-2532 Executing storage  command.
bilbobox4-fd: job.c:1365-2532 StorageCmd: storage address=bilbobox4.coreworks.de port=9103 ssl=0
bilbobox4-fd: job.c:1371-2532 Open storage: bilbobox4.coreworks.de:9103 ssl=0
bilbobox4-fd: bsock.c:201-2532 Current host[ipv4:172.16.1.204:9103] All host[ipv4:172.16.1.204:9103] 
bilbobox4-fd: bsock.c:155-2532 who=Storage daemon host=bilbobox4.coreworks.de port=9103
bilbobox4-fd: job.c:1383-2532 Connection OK to SD.
bilbobox4-fd: cram-md5.c:133-2532 cram-get received: auth cram-md5 <2084034969.1222760846@bilbobox4-sd> ssl=0
bilbobox4-fd: cram-md5.c:152-2532 sending resp to challenge: c6gRy5/5zVRoA++Hc6/ScA
bilbobox4-fd: cram-md5.c:80-2532 send: auth cram-md5 <896383970.1222760846@bilbobox4-fd> ssl=0
bilbobox4-fd: cram-md5.c:99-2532 Authenticate OK H6+HP7+Yw3+bPnYyg/5xKA
bilbobox4-fd: job.c:1392-2532 Authenticated with SD.
bilbobox4-fd: job.c:234-2532 <dird: bootstrap
bilbobox4-fd: job.c:250-2532 Executing bootstrap command.
bilbobox4-fd: job.c:234-2532 <dird: restore replace=a prelinks=0 where=/
bilbobox4-fd: job.c:250-2532 Executing restore command.
bilbobox4-fd: job.c:1678-2532 restore command
bilbobox4-fd: job.c:1699-2532 Got replace a, where=
bilbobox4-fd: job.c:1718-2532 bfiled>dird: 2000 OK restore
bilbobox4-fd: job.c:1776-2532 VolSessId=5 VolsessT=1222753667 SF=0 EF=0
bilbobox4-fd: job.c:1777-2532 JobId=2532 vol=DummyVolume
bilbobox4-fd: job.c:1784-2532 >stored: read open session = DummyVolume 5 1222753667 0 0 0 0
bilbobox4-fd: job.c:1790-2532 bfiled<stored: 3000 OK open ticket = 5
bilbobox4-fd: job.c:1795-2532 bfiled: got Ticket=5
bilbobox4-fd: job.c:1855-2532 3000 OK bootstrap
bilbobox4-fd: job.c:1809-2532 >stored: read data 5
bilbobox4-fd: job.c:1855-2532 3000 OK data
bilbobox4-fd: pythonlib.c:238-2532 No startup module.
bilbobox4-fd: fd_plugins.c:104-2532 plugin_ctx=9c08ea0 JobId=2532
bilbobox4-fd: restore.c:259-2532 Got hdr: Files=0 FilInx=3 Stream=26, 26.
bilbobox4-fd: restore.c:272-2532 Got stream: 26 len=3 extract=0
bilbobox4-fd: restore.c:667-2532 restore stream_plugin_name=0 0
bilbobox4-fd: fd_plugins.c:265-2532 Read plugin stream string=0 0
Variable "argc" is not available.
Variable "argc" is not available.
/usr/lib/bacula/btraceback.gdb:15: Error in sourced command file:
Variable "maxfd" is not available.
Using host libthread_db library "/lib/libthread_db.so.1".
[Thread debugging using libthread_db enabled]
[New Thread -1208502048 (LWP 12693)]
[New Thread 36633520 (LWP 12713)]
[Thread debugging using libthread_db enabled]
[New Thread -1208502048 (LWP 12693)]
[New Thread 36633520 (LWP 12713)]
[New Thread 26147760 (LWP 12694)]
[Thread debugging using libthread_db enabled]
[New Thread -1208502048 (LWP 12693)]
[New Thread 36633520 (LWP 12713)]
0x00356877 in ___newselect_nocancel () from /lib/libc.so.6
$1 = "bilbobox4-fd", '\0' <repeats 17 times>
$2 = 0x9bfb020 "bacula-fd"
$3 = 0x9bfb048 "/usr/sbin/bacula-fd"
$4 = 0x0
$5 = 0x813e9f3 "2.5.8 (29 September 2008)"
$6 = 0x81384d7 "i686-pc-linux-gnu"
$7 = 0x813ea5a "unknown"
$8 = 0x813ea5a "unknown"
$9 = "bilbobox4", '\0' <repeats 40 times>
#0  0x00356877 in ___newselect_nocancel () from /lib/libc.so.6
#1  0x08068734 in bnet_thread_server (addrs=0x9bfb8c8, max_clients=20, client_wq=0x817cf00, handle_client_request=0x8059010 <handle_client_request(void*)>)
    at bnet_server.c:161
#2  0x08050d9a in main (argc=) at filed.c:236

Thread 3 (Thread 26147760 (LWP 12694)):
#0  0x00cbab22 in pthread_cond_timedwait@@GLIBC_2.3.2 () from /lib/libpthread.so.0
#1  0x0807ff12 in watchdog_thread (arg=0x0) at watchdog.c:307
#2  0x00cb806f in start_thread () from /lib/libpthread.so.0
#3  0x0035cebe in clone () from /lib/libc.so.6
#4  0x018efbb0 in ?? ()

Thread 2 (Thread 36633520 (LWP 12713)):
#0  0x00cbdb11 in __waitpid_nocancel () from /lib/libpthread.so.0
#1  0x0807cd1f in signal_handler (sig=11) at signal.c:167
#2  <signal handler called>
#3  0x08055029 in plugin_name_stream (jcr=0x9c081c0, name=0x9c0a678 "0 0") at fd_plugins.c:282
#4  0x0805d328 in do_restore (jcr=0x9c081c0) at restore.c:668
#5  0x0805b1c8 in restore_cmd (jcr=0x9c081c0) at job.c:1737
#6  0x0805995b in handle_client_request (dirp=0x9c07e60) at job.c:251
#7  0x08080b9f in workq_server (arg=0x817cf00) at workq.c:357
#8  0x00cb806f in start_thread () from /lib/libpthread.so.0
#9  0x0035cebe in clone () from /lib/libc.so.6
#10 0x022efbb0 in ?? ()

Thread 1 (Thread -1208502048 (LWP 12693)):
#0  0x00356877 in ___newselect_nocancel () from /lib/libc.so.6
#1  0x08068734 in bnet_thread_server (addrs=0x9bfb8c8, max_clients=20, client_wq=0x817cf00, handle_client_request=0x8059010 <handle_client_request(void*)>)
    at bnet_server.c:161
#2  0x08050d9a in main (argc=) at filed.c:236
#0  0x00356877 in ___newselect_nocancel () from /lib/libc.so.6
#0  0x00356877 in ___newselect_nocancel () from /lib/libc.so.6
No symbol table info available.
#1  0x08068734 in bnet_thread_server (addrs=0x9bfb8c8, max_clients=20, client_wq=0x817cf00, handle_client_request=0x8059010 <handle_client_request(void*)>)
    at bnet_server.c:161
161	      if ((stat = select(maxfd + 1, &sockset, NULL, NULL, NULL)) < 0) {
Current language:  auto; currently c++
maxfd =

-------------------------------------------------------------------------
This SF.Net email is sponsored by the Moblin Your Move Developer's challenge
Build the coolest Linux based applications with Moblin SDK & win great prizes
Grand prize is a trip for two to an Open Source event anywhere in the world
http://moblin-contest.org/redirect.php?banner_id=100&url=/
_______________________________________________
Bacula-devel mailing list
Bacula-devel@xxxxxxxxxxxxxxxxxxxxx
https://lists.sourceforge.net/lists/listinfo/bacula-devel


This mailing list archive is a service of Copilotco.