Hello!
I just got a used Dell TL2000 tape library to use with Bacula (9.0.4 for
the director on Ubuntu 16.04, 9.0.6 for the storage director on Ubuntu
18.04).
I tested it with btape and mtx-changer and it passes all tests (strongly
inspired by Dan Langille's
https://dan.langille.org/2016/02/04/dell-tl4000-tape-library-running-btape-and-configuring-baculas-mtx-changer/
).
Once defined/integrated in Bacula, I can't label the tapes, bacula-director
crashes.
Here is what the session is at bconsole:
charles@bigzilla:/mnt/data/toburn/archive$ bconsole
Connecting to Director 192.168.0.53:9101
1000 OK: 103 bigzilla-dir Version: 9.0.4 (06 September 2017)
Enter a period to cancel a command.
*label barcodes
Automatically selected Catalog: MyCatalog
Using Catalog "MyCatalog"
The defined Storage resources are:
1: File3
2: TL2000
Select Storage resource (1-2): 2
Connecting to Storage daemon TL2000 at 192.168.0.17:9103 ...
Connecting to Storage daemon TL2000 at 192.168.0.17:9103 ...
3306 Issuing autochanger "slots" command.
Device "Autochanger" has 24 slots.
Connecting to Storage daemon TL2000 at 192.168.0.17:9103 ...
3301 Issuing autochanger "loaded? drive 0" command.
3302 Autochanger "loaded? drive 0", result: nothing loaded.
3306 Issuing autochanger "list" command.
The following Volumes will be labeled:
Slot Volume
==============
1 000151L3
2 000133L3
3 000128L3
4 000126L3
5 000122L3
6 000125L3
7 000124L3
8 000137L3
9 000129L3
10 000134L3
11 000131L3
12 000140L3
14 000153L3
15 000152L3
16 000132L3
17 000121L3
18 000127L3
19 000130L3
Do you want to label these Volumes? (yes|no): yes
Defined Pools:
1: Default
2: Default3
3: DefaultTape
4: Differential-File3
5: Differential-Tape
6: File
7: Full-File3
8: Full-Tape
9: Incremental-File3
10: Incremental-Tape
11: Photo-File
12: Scratch
Select the Pool (1-12): 12
Here are some details of my configuration:
charles@superbackup:/etc/bacula$ sudo lsscsi -g
[sudo] password for charles:
[3:0:0:0] disk ATA WDC WD20EARS-00M AB51 /dev/sda /dev/sg0
[7:0:0:0] cd/dvd HL-DT-ST DVDRAM GSA-H62L DL00 /dev/sr0 /dev/sg1
[8:0:0:0] disk ATA WDC WD20EARS-00M AB51 /dev/sdb /dev/sg2
[9:0:0:0] disk ATA WDC WD20EARX-00P AB51 /dev/sdc /dev/sg3
[10:0:0:0] disk ATA WDC WD20EARX-008 AB51 /dev/sdd /dev/sg4
[14:0:0:0] tape IBM ULT3580-TD3 73P5 /dev/st0 /dev/sg7
[14:0:0:1] mediumx IBM 3573-TL 8.40 /dev/sch0 /dev/sg8
[15:0:0:0] disk ATA WDC WD20EFRX-68E 0A82 /dev/sde /dev/sg5
[16:0:0:0] disk ATA WDC WD20EARS-00M AB51 /dev/sdf /dev/sg6
charles@superbackup:~$ sudo tapeinfo -f /dev/sg7
Product Type: Tape Drive
Vendor ID: 'IBM '
Product ID: 'ULT3580-TD3 '
Revision: '73P5'
Attached Changer API: No
SerialNumber: '1210306523'
MinBlock: 1
MaxBlock: 16777215
SCSI ID: 0
SCSI LUN: 0
Ready: no
Here is the relevant part of my bacula-sd.conf:
Autochanger {
Name = Autochanger
Device = Drive-1
Changer Command = "/etc/bacula/scripts/mtx-changer %c %o %S %a %d"
Changer Device = /dev/sg8
}
Device {
Name = Drive-1
Media Type = LTO-3
Device Type = Tape
Archive Device = /dev/nst0
AutomaticMount = yes # when device opened, read it
AlwaysOpen = yes
RemovableMedia = yes
RandomAccess = no
AutoChanger = yes
Drive Index = 0
# Enable the Alert command only if you have the mtx package loaded
Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
# If you have smartctl, enable this, it has more info than tapeinfo
# Alert Command = "sh -c 'smartctl -H -l error %c'"
Maximum Spool Size = 1024GB
Maximum Job Spool Size = 800GB
Spool Directory = /baculatmp
Maximum Concurrent Jobs = 1
Maximum Block Size = 1024K
Maximum File Size = 8GB
Hardware End of Medium = no
Fast Forward Space File = yes
}
And the relevant part of my bacula-dir.conf
Storage {
Name = TL2000
Address = 192.168.0.17
SDPort = 9103
Password = "xxxxxxxxxxxxxxxxxxxxxxxxxxx"
Device = Autochanger
Media Type = LTO-3
Maximum Concurrent Jobs = 1
Autochanger = yes
AllowCompression = yes
}
To further investigate the issue, I started bacula-sd and bacula-dir
manually in debug mode and captured below the messages it printed to the
console:
Messages generated by "sudo /usr/sbin/bacula-dir -d 999 -v -c
/etc/bacula/bacula-dir.conf -f -m"
bigzilla-dir: msgchan.c:120-0 Connect to Storage daemon 192.168.0.17:9103
bigzilla-dir: watchdog.c:197-0 Registered watchdog 7f0ec801cf08, interval
1800 one shot
bigzilla-dir: btimers.c:145-0 Start thread timer 7f0ec8021ab8 tid
7f0ed5d27700 for 1800 secs.
bigzilla-dir: bsock.c:237-0 Current 192.168.0.17:9103 All 192.168.0.17:9103
bigzilla-dir: bsock.c:166-0 who=Storage daemon host=192.168.0.17 port=9103
bigzilla-dir: bsock.c:349-0 OK connected to server Storage daemon
192.168.0.17:9103.
bigzilla-dir: btimers.c:203-0 Stop thread timer 7f0ec8021ab8
tid=7f0ed5d27700.
bigzilla-dir: watchdog.c:217-0 Unregistered watchdog 7f0ec801cf08
bigzilla-dir: watchdog.c:197-0 Registered watchdog 7f0ec801cf08, interval
600 one shot
bigzilla-dir: btimers.c:177-0 Start bsock timer 7f0ec8021ab8
tid=7f0ed5d27700 for 600 secs at 1526381821
bigzilla-dir: cram-md5.c:133-0 cram-get received: auth cram-md5
<651658768.1526381820@superbackup-sd> ssl=0
bigzilla-dir: cram-md5.c:157-0 sending resp to challenge:
5/+lrGpO76+YPR++Eh/AlC
bigzilla-dir: cram-md5.c:76-0 send: auth cram-md5 challenge
<435475706.1526381821@bigzilla-dir> ssl=0
bigzilla-dir: cram-md5.c:95-0 Authenticate OK fz+CUyMWb4+Zh5wwHV/n6C
bigzilla-dir: authenticate.c:155-0 >stored: 1000 OK auth
bigzilla-dir: authenticate.c:162-0 <stored: 3000 OK Hello 306
bigzilla-dir: btimers.c:190-0 Stop bsock timer 7f0ec8021ab8
tid=7f0ed5d27700 at 1526381821.
bigzilla-dir: watchdog.c:217-0 Unregistered watchdog 7f0ec801cf08
bigzilla-dir: ua_label.c:868-0 Add slot=1 Vol=000151L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=2 Vol=000133L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=3 Vol=000128L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=4 Vol=000126L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=5 Vol=000122L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=6 Vol=000125L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=7 Vol=000124L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=8 Vol=000137L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=9 Vol=000129L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=10 Vol=000134L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=11 Vol=000131L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=12 Vol=000140L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=14 Vol=000153L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=15 Vol=000152L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=16 Vol=000132L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=17 Vol=000121L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=18 Vol=000127L3 to SD list.
bigzilla-dir: ua_label.c:868-0 Add slot=19 Vol=000130L3 to SD list.
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=46 Pool NumVols=46
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=282 Pool NumVols=282
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=118 Pool NumVols=118
bigzilla-dir: sql_get.c:666-0 Actual NumVols=0 Pool NumVols=0
bigzilla-dir: sql_get.c:666-0 Actual NumVols=62 Pool NumVols=62
bigzilla-dir: sql_get.c:666-0 Actual NumVols=23 Pool NumVols=23
bigzilla-dir: sql_get.c:666-0 Actual NumVols=23 Pool NumVols=23
bigzilla-dir: signal.c:125-0 sig=11 Segmentation violation
Bacula interrupted by signal 11: Segmentation violation
Kaboom! bacula-dir, bigzilla-dir got signal 11 - Segmentation violation at
15-May-2018 12:57:09. Attempting traceback.
Kaboom! exepath=/usr/sbin/
bigzilla-dir: signal.c:201-0 Working=/var/lib/bacula
bigzilla-dir: signal.c:202-0 btpath=/usr/sbin/btraceback
bigzilla-dir: signal.c:203-0 exepath=/usr/sbin/bacula-dir
bigzilla-dir: signal.c:232-0 Doing waitpid
Calling: /usr/sbin/btraceback /usr/sbin/bacula-dir 22819 /var/lib/bacula
bsmtp: bsmtp.c:488-0 Failed to connect to mailhost localhost
bigzilla-dir: signal.c:125-0 sig=17 Child status has changed
bigzilla-dir: signal.c:234-0 Done waitpid
The btraceback call returned 1
LockDump: /var/lib/bacula/bacula.22819.traceback
bigzilla-dir: lockmgr.c:1179-0 lockmgr disabled
==== Traceback output ====
[New LWP 22820]
[New LWP 22821]
[New LWP 22824]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
0x00007f0edd7eec1d in nanosleep () at ../sysdeps/unix/syscall-template.S:84
84 ../sysdeps/unix/syscall-template.S: No such file or directory.
$1 = 1294808369
$2 = 2053597538
$3 = 21733992
$4 = 21734056
$5 = 0
$6 = 1953722192
$7 = -576371987
$8 = -576372020
$9 = -576372027
$10 = -576371993
$11 = 2053597538
$12 = -576372000
Environment variable "TestName" not defined.
#0 0x00007f0edd7eec1d in nanosleep () at
../sysdeps/unix/syscall-template.S:84
#1 0x00007f0edda12817 in bmicrosleep(int, int) () from /usr/lib/bacula/
libbac-9.0.4.so
#2 0x0000000000438814 in ?? ()
#3 0x000000000040de97 in ?? ()
#4 0x00007f0edd0b2830 in __libc_start_main (main=0x40d8b0, argc=8,
argv=0x7fffa7fb79f8, init=<optimized out>, fini=<optimized out>,
rtld_fini=<optimized out>, stack_end=0x7fffa7fb79e8) at
../csu/libc-start.c:291
#5 0x000000000040e729 in ?? ()
Thread 4 (Thread 0x7f0ed5d27700 (LWP 22824)):
#0 0x00007f0edd7eef7b in __waitpid (pid=22892, stat_loc=0x7f0ed5d253ec,
options=0) at ../sysdeps/unix/sysv/linux/waitpid.c:29
#1 0x00007f0edda3dc28 in signal_handler () from /usr/lib/bacula/
libbac-9.0.4.so
#2 <signal handler called>
#3 strlen () at ../sysdeps/x86_64/strlen.S:106
#4 0x0000000000445e83 in ?? ()
#5 0x000000000043ad55 in ?? ()
#6 0x000000000045c057 in ?? ()
#7 0x00007f0edda485fb in workq_server () from /usr/lib/bacula/
libbac-9.0.4.so
#8 0x00007f0edd7e56ba in start_thread (arg=0x7f0ed5d27700) at
pthread_create.c:333
#9 0x00007f0edd19941d in clone () at
../sysdeps/unix/sysv/linux/x86_64/clone.S:109
Thread 3 (Thread 0x7f0ed6528700 (LWP 22821)):
#0 pthread_cond_timedwait@@GLIBC_2.3.2 () at
../sysdeps/unix/sysv/linux/x86_64/pthread_cond_timedwait.S:225
#1 0x00007f0edda47cb4 in watchdog_thread () from /usr/lib/bacula/
libbac-9.0.4.so
#2 0x00007f0edd7e56ba in start_thread (arg=0x7f0ed6528700) at
pthread_create.c:333
#3 0x00007f0edd19941d in clone () at
../sysdeps/unix/sysv/linux/x86_64/clone.S:109
Thread 2 (Thread 0x7f0ed6d29700 (LWP 22820)):
#0 0x00007f0edd18f5d3 in select () at ../sysdeps/unix/syscall-template.S:84
#1 0x00007f0edda163b8 in bnet_thread_server(dlist*, int, workq_tag*, void*
(*)(void*)) () from /usr/lib/bacula/libbac-9.0.4.so
#2 0x000000000045bc0c in ?? ()
#3 0x00007f0edd7e56ba in start_thread (arg=0x7f0ed6d29700) at
pthread_create.c:333
#4 0x00007f0edd19941d in clone () at
../sysdeps/unix/sysv/linux/x86_64/clone.S:109
Thread 1 (Thread 0x7f0ede6b8740 (LWP 22819)):
#0 0x00007f0edd7eec1d in nanosleep () at
../sysdeps/unix/syscall-template.S:84
#1 0x00007f0edda12817 in bmicrosleep(int, int) () from /usr/lib/bacula/
libbac-9.0.4.so
#2 0x0000000000438814 in ?? ()
#3 0x000000000040de97 in ?? ()
#4 0x00007f0edd0b2830 in __libc_start_main (main=0x40d8b0, argc=8,
argv=0x7fffa7fb79f8, init=<optimized out>, fini=<optimized out>,
rtld_fini=<optimized out>, stack_end=0x7fffa7fb79e8) at
../csu/libc-start.c:291
#5 0x000000000040e729 in ?? ()
#0 0x00007f0edd7eec1d in nanosleep () at
../sysdeps/unix/syscall-template.S:84
84 in ../sysdeps/unix/syscall-template.S
No locals.
#1 0x00007f0edda12817 in bmicrosleep(int, int) () from /usr/lib/bacula/
libbac-9.0.4.so
No symbol table info available.
#2 0x0000000000438814 in ?? ()
No symbol table info available.
#3 0x000000000040de97 in ?? ()
No symbol table info available.
#4 0x00007f0edd0b2830 in __libc_start_main (main=0x40d8b0, argc=8,
argv=0x7fffa7fb79f8, init=<optimized out>, fini=<optimized out>,
rtld_fini=<optimized out>, stack_end=0x7fffa7fb79e8) at
../csu/libc-start.c:291
291 ../csu/libc-start.c: No such file or directory.
result = <optimized out>
unwind_buf = {cancel_jmp_buf = {{jmp_buf = {0, 8966355806345791312,
4253440, 140736011663856, 0, 0, -8966198045079206064,
-9046782211473242288}, mask_was_saved = 0}}, priv = {pad = {0x0, 0x0, 0x8,
0x40d8b0}, data = {prev = 0x0, cleanup = 0x0, canceltype = 8}}}
not_first_call = <optimized out>
#5 0x000000000040e729 in ?? ()
No symbol table info available.
#0 0x0000000000000000 in ?? ()
No symbol table info available.
#0 0x0000000000000000 in ?? ()
No symbol table info available.
Attempt to dump current JCRs. njcrs=2
threadid=0x7f0ede6b8740 JobId=0 JobStatus=R jcr=0x150ba08
name=*JobMonitor*.2018-05-15_12.56.49_01
use_count=1 killable=0
JobType=I JobLevel=
sched_time=15-May-2018 12:56 start_time=15-May-2018 12:56
end_time=01-Jan-1970 01:00 wait_time=01-Jan-1970 01:00
db=(nil) db_batch=(nil) batch_started=0
wstore=0x14d0d68 rstore=(nil) wjcr=(nil) client=0x14c9ff8
reschedule_count=0 SD_msg_chan_started=0
threadid=0x7f0ed5d27700 JobId=0 JobStatus=R jcr=0x7f0ec80008e8
name=-Console-.2018-05-15_12.56.53_02
use_count=1 killable=0
JobType=U JobLevel=
sched_time=15-May-2018 12:56 start_time=15-May-2018 12:56
end_time=01-Jan-1970 01:00 wait_time=01-Jan-1970 01:00
db=0x7f0ec80031d8 db_batch=(nil) batch_started=0
wstore=0x14d12c8 rstore=(nil) wjcr=(nil) client=0x14c9ff8
reschedule_count=0 SD_msg_chan_started=0
BDB=0x7f0ec80031d8 db_name=bacula db_user=bacula connected=true
cmd="SELECT
MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolABytes,VolHoleBytes,VolHoles,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,VolType,VolParts,VolCloudParts,LastPartBytes,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,CacheRetention
FROM Media WHERE VolumeName='000151L3'" changes=0
RWLOCK=0x7f0ec80031f0 w_active=0 w_wait=0
List plugins. Hook count=0
bigzilla-dir: signal.c:125-0 sig=17 Child status has changed
==== End traceback output ====
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 1048 bytes at
14c09a8 from postgresql.c:83
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
14ef858 from jcr.c:388
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 1048 bytes at
14f0908 from postgresql.c:81
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
14f0d58 from jcr.c:384
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 432 bytes at
7f0ed00008e8 from bnet.c:570
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 4120 bytes at
7f0ed0000ad8 from bnet.c:579
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 4120 bytes at
7f0ed0001b28 from bnet.c:580
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ed0002b78 from bnet.c:581
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 7 bytes at
7f0ed0002dc8 from bnet.c:582
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 13 bytes at
7f0ed0002e08 from bnet.c:583
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 16 bytes at
7f0ed0002e48 from workq.c:198
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec8001488 from jcr.c:372
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec80014d8 from jcr.c:386
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8001728 from jcr.c:390
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8001878 from job.c:1568
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec80019c8 from job.c:1571
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8001b18 from job.c:1574
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8001d68 from job.c:1577
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8001fb8 from job.c:1580
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8002338 from job.c:1764
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 154 bytes at
7f0ec8002588 from job.c:1593
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 6 bytes at
7f0ec8002208 from ua_server.c:93
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 776 bytes at
7f0ec8002658 from ua_server.c:185
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8002998 from ua_server.c:189
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8002ae8 from ua_server.c:190
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8002c38 from ua_server.c:191
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8002dc8 from dird_conf.h:381
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 584 bytes at
7f0ec80031d8 from postgresql.c:137
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec8003458 from sql.c:173
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 536 bytes at
7f0ec80036a8 from sql.c:174
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec80038f8 from postgresql.c:75
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 11 bytes at
7f0ec8002d88 from postgresql.c:79
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003948 from postgresql.c:84
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003a98 from postgresql.c:87
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003be8 from postgresql.c:88
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003d38 from postgresql.c:89
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003e88 from postgresql.c:90
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8003fd8 from postgresql.c:91
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 280 bytes at
7f0ec8004128 from postgresql.c:97
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 7 bytes at
7f0ec8003198 from postgresql.c:141
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 7 bytes at
7f0ec8004278 from postgresql.c:142
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec80042b8 from postgresql.c:144
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 10 bytes at
7f0ec80042f8 from postgresql.c:147
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 7 bytes at
7f0ec8004338 from postgresql.c:155
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 32 bytes at
7f0ec8002248 from job.c:1787
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 80 bytes at
7f0ec8020fe8 from alist.c:55
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 25 bytes at
7f0ec800cb98 from ua_label.c:523
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec80211f8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec8020638 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec8021848 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec8021568 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801f828 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec8003138 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec80209e8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec8021b88 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec8021bc8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec80022a8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec80022e8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec8020db8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec8020df8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec80210b8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec80210f8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801f8c8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801f908 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801f478 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801f4b8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801f508 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801f548 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801f598 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ea08 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801ea58 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ea98 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801eae8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801eb28 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801eb78 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ebb8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801ec08 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ec48 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801ec98 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ecd8 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801ed28 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 24 bytes at
7f0ec801ed68 from ua_label.c:858
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 9 bytes at
7f0ec801edb8 from ua_label.c:864
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 160 bytes at
7f0ec801d4f8 from bsys.c:388
bigzilla-dir: smartall.c:400-0 Orphaned buffer: bigzilla-dir 160 bytes at
7f0ec801edf8 from bsys.c:388
Messages generated by "sudo /usr/sbin/bacula-sd -d 999 -v -c
/etc/bacula/bacula-sd.conf -f"
superbackup-sd: bnet.c:569-0 socket=4 who=client host=192.168.0.53 port=9103
superbackup-sd: dircmd.c:188-0 Got a DIR connection at 15-mai-2018 12:56:59
superbackup-sd: jcr.c:931-0 set_jcr_job_status(0, C)
superbackup-sd: jcr.c:940-0 OnEntry JobStatus=0 newJobstatus=C
superbackup-sd: jcr.c:951-0 Set new stat. old: 0,0 new: C,0
superbackup-sd: jcr.c:956-0 leave setJobStatus old=0 new=C
superbackup-sd: watchdog.c:197-0 Registered watchdog 7f10bc0012e8, interval
600 one shot
superbackup-sd: btimers.c:177-0 Start bsock timer 7f10bc001278
tid=7f10c3fff700 for 600 secs at 1526381819
superbackup-sd: cram-md5.c:69-0 send: auth cram-md5 challenge
<1260624335.1526381819@superbackup-sd> ssl=0
superbackup-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<636874980.1526381820@bigzilla-dir> ssl=0
superbackup-sd: cram-md5.c:157-0 sending resp to challenge:
bWw7W/hBp+M6m9+KeU+iUA
superbackup-sd: btimers.c:190-0 Stop bsock timer 7f10bc001278
tid=7f10c3fff700 at 1526381819.
superbackup-sd: watchdog.c:217-0 Unregistered watchdog 7f10bc0012e8
superbackup-sd: dircmd.c:214-0 Message channel init completed.
superbackup-sd: dircmd.c:225-0 <dird: autochanger slots Autochanger
superbackup-sd: dircmd.c:239-0 Do command: autochanger
superbackup-sd: dircmd.c:1107-0 Try changer device Drive-1
superbackup-sd: dircmd.c:1127-0 Found changer device Drive-1
superbackup-sd: dircmd.c:1140-0 Found device Drive-1
superbackup-sd: block_util.c:167-0 Rechdr len=1048576 max_items=52428
superbackup-sd: block_util.c:286-0 empty_block: adata=0 len=1048576 set
binbuf=24
superbackup-sd: block_util.c:170-0 New block adata=0 len=1048576
block=7f10bc001980
superbackup-sd: acquire.c:658-0 Attach 0xbc001368 to dev "Drive-1"
(/dev/nst0)
superbackup-sd: acquire.c:704-0 JobId=0 enter attach_dcr_to_dev
superbackup-sd: lock.c:159-0 Lock Drive-1 from dircmd.c:1755 precnt=0
superbackup-sd: autochanger.c:328-0 Locking changer Autochanger
superbackup-sd: autochanger.c:804-0 omsg=/etc/bacula/scripts/mtx-changer
/dev/sg8 slots 0 /dev/nst0 0
superbackup-sd: watchdog.c:197-0 Registered watchdog 7f10bc0012e8, interval
300
superbackup-sd: btimers.c:67-0 Start child timer 7f10bc201e48, pid 30185
for 300 secs.
superbackup-sd: autochanger.c:695-0 <stored: slots=24
superbackup-sd: bpipe.c:273-0 Wait for 30185 opt=1
superbackup-sd: bpipe.c:285-0 Got wpid=0 status=0 ERR=none
superbackup-sd: bpipe.c:285-0 Got wpid=0 status=0 ERR=none
superbackup-sd: signal.c:125-0 sig=17 Child status has changed
superbackup-sd: bnet.c:569-0 socket=6 who=client host=192.168.0.53 port=9103
superbackup-sd: dircmd.c:188-0 Got a DIR connection at 15-mai-2018 12:57:00
superbackup-sd: jcr.c:931-0 set_jcr_job_status(0, C)
superbackup-sd: jcr.c:940-0 OnEntry JobStatus=0 newJobstatus=C
superbackup-sd: jcr.c:951-0 Set new stat. old: 0,0 new: C,0
superbackup-sd: jcr.c:956-0 leave setJobStatus old=0 new=C
superbackup-sd: watchdog.c:197-0 Registered watchdog 7f10b4001a28, interval
600 one shot
superbackup-sd: btimers.c:177-0 Start bsock timer 7f10b40019b8
tid=7f10c37fe700 for 600 secs at 1526381820
superbackup-sd: cram-md5.c:69-0 send: auth cram-md5 challenge
<651658768.1526381820@superbackup-sd> ssl=0
superbackup-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<435475706.1526381821@bigzilla-dir> ssl=0
superbackup-sd: cram-md5.c:157-0 sending resp to challenge:
fz+CUyMWb4+Zh5wwHV/n6C
superbackup-sd: btimers.c:190-0 Stop bsock timer 7f10b40019b8
tid=7f10c37fe700 at 1526381820.
superbackup-sd: watchdog.c:217-0 Unregistered watchdog 7f10b4001a28
superbackup-sd: dircmd.c:214-0 Message channel init completed.
superbackup-sd: dircmd.c:225-0 <dird: autochanger list Autochanger
superbackup-sd: dircmd.c:239-0 Do command: autochanger
superbackup-sd: dircmd.c:1107-0 Try changer device Drive-1
superbackup-sd: dircmd.c:1127-0 Found changer device Drive-1
superbackup-sd: dircmd.c:1140-0 Found device Drive-1
superbackup-sd: block_util.c:167-0 Rechdr len=1048576 max_items=52428
superbackup-sd: block_util.c:286-0 empty_block: adata=0 len=1048576 set
binbuf=24
superbackup-sd: block_util.c:170-0 New block adata=0 len=1048576
block=7f10b4002190
superbackup-sd: acquire.c:658-0 Attach 0xb4001b78 to dev "Drive-1"
(/dev/nst0)
superbackup-sd: acquire.c:704-0 JobId=0 enter attach_dcr_to_dev
superbackup-sd: lock.c:159-0 Lock Drive-1 from dircmd.c:1755 precnt=1
superbackup-sd: bpipe.c:273-0 Wait for 30185 opt=1
superbackup-sd: bpipe.c:281-0 Got break wpid=30185 status=0 ERR=none
superbackup-sd: bpipe.c:302-0 child status=0
superbackup-sd: btimers.c:79-0 Stop child timer 7f10bc201e48 pid 30185
superbackup-sd: watchdog.c:217-0 Unregistered watchdog 7f10bc0012e8
superbackup-sd: bpipe.c:317-0 returning stat=0,0
superbackup-sd: autochanger.c:342-0 Unlocking changer Autochanger
superbackup-sd: lock.c:169-0 Unlock Drive-1 from dircmd.c:1767 postcnt=0
superbackup-sd: acquire.c:722-0 Enter detach_dcr_from_dev
superbackup-sd: lock.c:159-0 Lock Drive-1 from acquire.c:724 precnt=0
superbackup-sd: lock.c:169-0 Unlock Drive-1 from acquire.c:745 postcnt=0
superbackup-sd: block_util.c:254-0 free_block buffer=7f10bc001a60
superbackup-sd: block_util.c:261-0 === free_block block 7f10bc001980
superbackup-sd: record_util.c:277-0 Enter free_record.
superbackup-sd: record_util.c:281-0 Data buf is freed.
superbackup-sd: record_util.c:283-0 Leave free_record.
superbackup-sd: sd_plugins.c:102-0 No b_plugin_list: generate_plugin_event
ignored.
superbackup-sd: message.c:561-0 Close_msg jcr=7f10bc000b48
superbackup-sd: job.c:323-0 End Job JobId=0 7f10bc000b48
superbackup-sd: job.c:331-0 Send terminate jid=0 7f10bc000b48
superbackup-sd: message.c:561-0 Close_msg jcr=0
superbackup-sd: message.c:586-0 ===Begin close msg resource at 55acdc932228
superbackup-sd: message.c:675-0 Done walking message chain.
superbackup-sd: message.c:682-0 ===End close msg resource
superbackup-sd: autochanger.c:328-0 Locking changer Autochanger
superbackup-sd: message.c:1417-0 Enter Jmsg type=6
superbackup-sd: autochanger.c:804-0 omsg=/etc/bacula/scripts/mtx-changer
/dev/sg8 loaded 0 /dev/nst0 0
superbackup-sd: autochanger.c:284-0 Run
program=/etc/bacula/scripts/mtx-changer /dev/sg8 loaded 0 /dev/nst0 0
superbackup-sd: watchdog.c:197-0 Registered watchdog 7f10b4001a28, interval
300
superbackup-sd: btimers.c:67-0 Start child timer 7f10b420a5e8, pid 30193
for 300 secs.
superbackup-sd: signal.c:125-0 sig=17 Child status has changed
superbackup-sd: bpipe.c:472-0 Run program fgets stat=0
superbackup-sd: bpipe.c:501-0 resadr=0xb4202400 reslen=2 res=0
superbackup-sd: bpipe.c:273-0 Wait for 30193 opt=1
superbackup-sd: bpipe.c:281-0 Got break wpid=30193 status=0 ERR=none
superbackup-sd: bpipe.c:302-0 child status=0
superbackup-sd: btimers.c:79-0 Stop child timer 7f10b420a5e8 pid 30193
superbackup-sd: watchdog.c:217-0 Unregistered watchdog 7f10b4001a28
superbackup-sd: bpipe.c:317-0 returning stat=0,0
superbackup-sd: bpipe.c:505-0 Run program returning 0
superbackup-sd: autochanger.c:286-0 run_prog:
/etc/bacula/scripts/mtx-changer /dev/sg8 loaded 0 /dev/nst0 0 stat=0
result=0
superbackup-sd: message.c:1417-0 Enter Jmsg type=6
superbackup-sd: autochanger.c:342-0 Unlocking changer Autochanger
superbackup-sd: autochanger.c:328-0 Locking changer Autochanger
superbackup-sd: autochanger.c:804-0 omsg=/etc/bacula/scripts/mtx-changer
/dev/sg8 list 0 /dev/nst0 0
superbackup-sd: watchdog.c:197-0 Registered watchdog 7f10b4001a28, interval
300
superbackup-sd: btimers.c:67-0 Start child timer 7f10b420a5e8, pid 30206
for 300 secs.
superbackup-sd: autochanger.c:682-0 <stored: 1:000151L3
superbackup-sd: autochanger.c:682-0 <stored: 2:000133L3
superbackup-sd: autochanger.c:682-0 <stored: 3:000128L3
superbackup-sd: autochanger.c:682-0 <stored: 4:000126L3
superbackup-sd: autochanger.c:682-0 <stored: 5:000122L3
superbackup-sd: autochanger.c:682-0 <stored: 6:000125L3
superbackup-sd: autochanger.c:682-0 <stored: 7:000124L3
superbackup-sd: autochanger.c:682-0 <stored: 8:000137L3
superbackup-sd: autochanger.c:682-0 <stored: 9:000129L3
superbackup-sd: autochanger.c:682-0 <stored: 10:000134L3
superbackup-sd: autochanger.c:682-0 <stored: 11:000131L3
superbackup-sd: autochanger.c:682-0 <stored: 12:000140L3
superbackup-sd: autochanger.c:682-0 <stored: 14:000153L3
superbackup-sd: autochanger.c:682-0 <stored: 15:000152L3
superbackup-sd: autochanger.c:682-0 <stored: 16:000132L3
superbackup-sd: autochanger.c:682-0 <stored: 17:000121L3
superbackup-sd: autochanger.c:682-0 <stored: 18:000127L3
superbackup-sd: autochanger.c:682-0 <stored: 19:000130L3
superbackup-sd: signal.c:125-0 sig=17 Child status has changed
superbackup-sd: bpipe.c:273-0 Wait for 30206 opt=1
superbackup-sd: bpipe.c:281-0 Got break wpid=30206 status=0 ERR=none
superbackup-sd: bpipe.c:302-0 child status=0
superbackup-sd: btimers.c:79-0 Stop child timer 7f10b420a5e8 pid 30206
superbackup-sd: watchdog.c:217-0 Unregistered watchdog 7f10b4001a28
superbackup-sd: bpipe.c:317-0 returning stat=0,0
superbackup-sd: autochanger.c:342-0 Unlocking changer Autochanger
superbackup-sd: lock.c:169-0 Unlock Drive-1 from dircmd.c:1767 postcnt=0
superbackup-sd: acquire.c:722-0 Enter detach_dcr_from_dev
superbackup-sd: lock.c:159-0 Lock Drive-1 from acquire.c:724 precnt=0
superbackup-sd: lock.c:169-0 Unlock Drive-1 from acquire.c:745 postcnt=0
superbackup-sd: block_util.c:254-0 free_block buffer=7f10b4002270
superbackup-sd: block_util.c:261-0 === free_block block 7f10b4002190
superbackup-sd: record_util.c:277-0 Enter free_record.
superbackup-sd: record_util.c:281-0 Data buf is freed.
superbackup-sd: record_util.c:283-0 Leave free_record.
superbackup-sd: sd_plugins.c:102-0 No b_plugin_list: generate_plugin_event
ignored.
superbackup-sd: message.c:561-0 Close_msg jcr=7f10b4000b48
superbackup-sd: job.c:323-0 End Job JobId=0 7f10b4000b48
superbackup-sd: job.c:331-0 Send terminate jid=0 7f10b4000b48
superbackup-sd: message.c:561-0 Close_msg jcr=0
superbackup-sd: message.c:586-0 ===Begin close msg resource at 55acdc932228
superbackup-sd: message.c:675-0 Done walking message chain.
superbackup-sd: message.c:682-0 ===End close msg resource
Could anybody tell me where the problem could be? Could it be about the
versions mismatch? I saw on the list that Dan Langille wrote (
https://sourceforge.net/p/bacula/mailman/message/36187800/): "bacula-dir =
bacula-sd >= bacula-fd" How close should the versions of -dir and -sd be?
In my case they are 0.0.2 apart.
Thanks!
Charles
--
Charles Nadeau Ph.D.
http://charlesnadeau.blogspot.com/
------------------------------------------------------------------------------
Check out the vibrant tech community on one of the world's most
engaging tech sites, Slashdot.org! http://sdm.link/slashdot
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
