I've put some serious time/effort into getting backup to DVD working with
1.38.9. It's got to be something simple/dumb that I'm not doing because no
amount of searching is turning up anything similar.
Bacula reports a successful backup, however, it only writes to the spool
directory. I never see a call to dvd-handler /dev/hdc write 1 <temp backup> in
the logs. The appropriate info follows. Can anyone see what I'm doing wrong?
I've tried both + and -. In addition, I can manually run:
/etc/bacula/dvd-handler /dev/hdc write 1 /tmp/backup/DVD2006-07-09-4
And the file is successfully copied to the DVD. I think the problem is that
Bacula never gets to this call, for reasons I can't determine.
I am running both dir and sd daemons as root.
Thanks in advance,
Ben
==========
DIR Report
==========
Job started. JobId=5
09-Jul 14:07 Server-dir: Start Backup JobId 5,
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
09-Jul 14:07 Server-dir: Created new Volume "DVD2006-07-09-4" in catalog.
09-Jul 14:08 Server-sd: Labeled new Volume "DVD2006-07-09-4" on device "DVD"
(/dev/hdc).
09-Jul 14:08 Server-sd: Wrote label to prelabeled Volume "DVD2006-07-09-4" on
device "DVD" (/dev/hdc)
09-Jul 14:08 Server-dir: Max Volume jobs exceeded. Marking Volume
"DVD2006-07-09-4" as Used.
09-Jul 14:08 Server-dir: Bacula 1.38.9 (02May06): 09-Jul-2006 14:08:15
JobId: 5
Job: BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Backup Level: Full
Client: "BC-BensLaptop" Windows XP,MVS,NT 5.1.2600
FileSet: "Windows-XP" 2006-07-09 13:17:26
Pool: "Default"
Storage: "DVD"
Scheduled time: 09-Jul-2006 14:07:51
Start time: 09-Jul-2006 14:07:57
End time: 09-Jul-2006 14:08:15
Elapsed time: 18 secs
Priority: 10
FD Files Written: 14
SD Files Written: 14
FD Bytes Written: 315,852 (315.8 KB)
SD Bytes Written: 318,284 (318.2 KB)
Rate: 17.5 KB/s
Software Compression: None
Volume name(s): DVD2006-07-09-4
Volume Session Id: 1
Volume Session Time: 1152468465
Last Volume Bytes: 319,381 (319.3 KB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
09-Jul 14:08 Server-dir: Begin pruning Jobs.
09-Jul 14:08 Server-dir: No Jobs found to prune.
09-Jul 14:08 Server-dir: Begin pruning Files.
09-Jul 14:08 Server-dir: No Files found to prune.
09-Jul 14:08 Server-dir: End auto prune.
=======================
Spool dir (/tmp/backup)
=======================
-rw-r----- 1 root root 319782 Jul 9 14:08 DVD2006-07-09-4
==============
bacula-sd.conf
==============
Device {
Name = DVD
Media Type = DVD
Archive Device = /dev/hdc
LabelMedia = yes
Random Access = yes
AutomaticMount = yes
RemovableMedia = yes
AlwaysOpen = no
MaximumPartSize = 800M
RequiresMount = yes
MountPoint = /mnt/dvd
MountCommand = "/bin/mount -t iso9660 -o ro %a %m"
UnmountCommand = "/bin/umount %m"
SpoolDirectory = /tmp/backup
WritePartCommand = "/etc/bacula/dvd-handler %a write %e %v"
FreeSpaceCommand = "/etc/bacula/dvd-handler %a free"
}
===============
bacula-dir.conf
===============
Storage {
# Do not use "localhost" here
# DO NOT REMOVE - Webconfig NO_DELETE
Name = DVD
Address = "192.168.11.145"
SDPort = 9103
Password = "filepassword"
Device = DVD
MediaType = DVD
}
Pool {
# DO NOT REMOVE - Webconfig NO_DELETE
Name = "Default"
PoolType = "Backup"
Recycle = "no"
AutoPrune = "no"
VolumeRetention = 2 years
Accept Any Volume = yes
Maximum Volume Bytes = 2147483500
Maximum Volume Jobs = 1
Volume Use Duration = 1d
Label Format = "DVD${Year}-${Month:p/2/0/r}-${Day:p/2/0/r}-${NumVols}"
}
=============================
Results of debug output on SD
=============================
Jul 9 14:07:54 backup bacula-sd: Server-sd: jcr.c:116 read_last_jobs seek to
188
Jul 9 14:07:54 backup bacula-sd: Server-sd: jcr.c:123 Read num_items=10
Jul 9 14:07:54 backup bacula-sd: Server-sd: bpipe.c:292 Run program returning 0
Jul 9 14:07:54 backup bacula-sd: Server-sd: bnet_server.c:83 Addresses
host[ipv4:0.0.0.0:9103]
Jul 9 14:07:54 backup bacula-sd: Server-sd: stored.c:453 calling init_dev
/dev/hdc
Jul 9 14:07:54 backup bacula-sd: Server-sd: dev.c:239 init_dev: tape=0
dev_name=/dev/hdc
Jul 9 14:07:54 backup bacula-sd: Server-sd: stored.c:455 SD init done /dev/hdc
Jul 9 14:07:54 backup bacula-sd: Server-sd: mem_pool.c:363 garbage collect
memory pool
Jul 9 14:07:54 backup bacula-sd: Server-sd: bnet.c:1128 who=client
host=192.168.11.145 port=36643
Jul 9 14:07:54 backup bacula-sd: Server-sd: dircmd.c:157 Conn: Hello Director
Server-dir calling
Jul 9 14:07:54 backup bacula-sd: Server-sd: dircmd.c:166 Start Dir Job
Jul 9 14:07:54 backup bacula-sd: Server-sd: cram-md5.c:52 send: auth cram-md5
<[EMAIL PROTECTED]> ssl=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: cram-md5.c:68 Authenticate OK
UntXnRJLXQ/NO9/BV4tSzB
Jul 9 14:07:55 backup bacula-sd: Server-sd: cram-md5.c:97 cram-get: auth
cram-md5 <[EMAIL PROTECTED]> ssl=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: cram-md5.c:114 sending resp to
challenge: QmknJ4/jkSlRSWAvv1+lFA
Jul 9 14:07:55 backup bacula-sd: Server-sd: dircmd.c:187 Message channel init
completed.
Jul 9 14:07:55 backup bacula-sd: Server-sd: dircmd.c:194 <dird: JobId=5
job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 job_name=BensLaptop_2_BC-DVD-RW
client_name=BC-BensLaptop type=66 level=70 FileSet=Windows-XP NoAttr=0
SpoolAttr=0 FileSetMD5=Q7+AphkdyW+nEC+C85Yp3D SpoolData=0 WritePartAfterJob=0
PreferMountedVols=1
Jul 9 14:07:55 backup bacula-sd: Server-sd: dircmd.c:208 Do command: JobId=
Jul 9 14:07:55 backup bacula-sd: Server-sd: job.c:72 <dird: JobId=5
job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 job_name=BensLaptop_2_BC-DVD-RW
client_name=BC-BensLaptop type=66 level=70 FileSet=Windows-XP NoAttr=0
SpoolAttr=0 FileSetMD5=Q7+AphkdyW+nEC+C85Yp3D SpoolData=0 WritePartAfterJob=0
PreferMountedVols=1
Jul 9 14:07:55 backup bacula-sd: Server-sd: job.c:125 >dird: 3000 OK Job
SDid=1
SDtime=1152468465 Authorization=PMEL-NNHE-MKFG-LEKC-HLGF-BFNJ-BJON-OCHA
Jul 9 14:07:55 backup bacula-sd: Server-sd: dircmd.c:194 <dird: use
storage=DVD
media_type=DVD pool_name=Default pool_type=Backup append=1 copy=0 stripe=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: dircmd.c:208 Do command: use
storage=
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:353 <dird: use
storage=DVD media_type=DVD pool_name=Default pool_type=Backup append=1 copy=0
stripe=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:376 <dird device: use
device=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:457 PrefMnt=1 exact=1
suitable=0 chgronly=0 any=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:548 PrefMnt=1 exact=1
suitable=0 chgronly=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:586 Search res for DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:623 Try match res=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:659 MediaType device=DVD
request=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:683 Try reserve DVD
JobId=5
Jul 9 14:07:55 backup bacula-sd: Server-sd: askdir.c:232
dir_find_next_appendable_volume
Jul 9 14:07:55 backup bacula-sd: Server-sd: askdir.c:245 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 FindMedia=1 pool_name=Default
media_type=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: askdir.c:165 <dird 1000 OK
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0
VolErrors=0 VolWrites=0 MaxVolBytes=2147483500 VolCapacityBytes=0
VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0
VolWriteTime=0 EndFile=0 EndBlock=0 VolParts=0 LabelType=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:146 New
Vol=DVD2006-07-09-4 dev="DVD" (/dev/hdc)
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:700 JobId=5 looking for
Volume=DVD2006-07-09-4
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:824 reserve_append
device
is disk
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:855 PrefMnt=1 exact=1
suitable=1 chgronly=0 any=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:906 failed: Not exact
match have= want=DVD2006-07-09-4
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:828 can_reserve_drive!=1
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:245 free_unused_olume
DVD2006-07-09-4
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:725 Not OK.
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:561 Suitable device
found=DVD, not used: busy
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:465 PrefMnt=1 exact=0
suitable=1 chgronly=0 any=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:548 PrefMnt=1 exact=0
suitable=1 chgronly=0
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:586 Search res for DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:623 Try match res=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:659 MediaType device=DVD
request=DVD
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:683 Try reserve DVD
JobId=5
Jul 9 14:07:55 backup bacula-sd: Server-sd: reserve.c:824 reserve_append
device
is disk
Jul 9 14:07:56 backup bacula-sd: Server-sd: reserve.c:855 PrefMnt=1 exact=0
suitable=1 chgronly=0 any=0
Jul 9 14:07:56 backup bacula-sd: Server-sd: reserve.c:960 OK Dev avail
reserved
"DVD" (/dev/hdc) JobId=5
Jul 9 14:07:56 backup bacula-sd: Server-sd: reserve.c:834 Inc reserve=1
dev="DVD" (/dev/hdc) 0x8c6b5d8
Jul 9 14:07:56 backup bacula-sd: Server-sd: reserve.c:712 Reserved=1
dev_name=DVD mediatype=DVD pool=Default ok=1
Jul 9 14:07:56 backup bacula-sd: Server-sd: reserv
Jul 9 14:08:00 backup kernel: Unable to identify CD-ROM format.
Jul 9 14:08:03 backup last message repeated 3 times
Jul 9 14:08:03 backup bacula-sd: e.c:633 >dird dev: 3000 OK use device
device=DVD
Jul 9 14:08:03 backup bacula-sd: Server-sd: reserve.c:557 Suitable device
found=DVD
Jul 9 14:08:03 backup bacula-sd: Server-sd: dircmd.c:194 <dird: runServer-sd:
dircmd.c:208 Do command: run
Jul 9 14:08:03 backup bacula-sd: Server-sd: job.c:139 Run_cmd: run
Jul 9 14:08:03 backup bacula-sd: Server-sd: job.c:157
BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 waiting on FD to contact SD
Jul 9 14:08:03 backup bacula-sd: Server-sd: bnet.c:1128 who=client
host=192.168.11.149 port=36643
Jul 9 14:08:03 backup bacula-sd: Server-sd: dircmd.c:157 Conn: Hello Start Job
BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Jul 9 14:08:03 backup bacula-sd: Server-sd: job.c:199 Found Job
BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Jul 9 14:08:03 backup bacula-sd: Server-sd: cram-md5.c:52 send: auth cram-md5
<[EMAIL PROTECTED]> ssl=0
Jul 9 14:08:03 backup bacula-sd: Server-sd: cram-md5.c:68 Authenticate OK
VT/jbnMD/Q+Gbi/nzXpE9D
Jul 9 14:08:03 backup bacula-sd: Server-sd: cram-md5.c:97 cram-get: auth
cram-md5 <[EMAIL PROTECTED]> ssl=0
Jul 9 14:08:03 backup bacula-sd: Server-sd: cram-md5.c:114 sending resp to
challenge: t+EG9//9qnQwqx+5fn/i8C
Jul 9 14:08:03 backup bacula-sd: Server-sd: job.c:216 OK Authentication Job
BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Jul 9 14:08:03 backup bacula-sd: Server-sd: job.c:175 Running job
BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:118 Start run
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:135 <filed: append open
session
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:212 Append open session:
append open session
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:222 >filed: 3000 OK open
ticket = 1
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:135 <filed: append data 1
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:174 Append data: append
data 1
Jul 9 14:08:03 backup bacula-sd: Server-sd: fd_cmds.c:176 <bfiled: append data
1
Jul 9 14:08:03 backup bacula-sd: Server-sd: append.c:58 Start append data.
res=1
Jul 9 14:08:03 backup bacula-sd: Server-sd: acquire.c:226 acquire_append
device
is DVD
Jul 9 14:08:03 backup bacula-sd: Server-sd: acquire.c:292 Not in append mode,
try mount.
Jul 9 14:08:03 backup bacula-sd: Server-sd: acquire.c:298 Do
mount_next_write_vol
Jul 9 14:08:03 backup bacula-sd: Server-sd: mount.c:51 Enter
mount_next_volume(release=0)
Jul 9 14:08:03 backup bacula-sd: Server-sd: mount.c:61 mount_next_vol retry=0
Jul 9 14:08:03 backup bacula-sd: Server-sd: mount.c:87 Before
dir_find_next_appendable_volume.
Jul 9 14:08:03 backup bacula-sd: Server-sd: askdir.c:232
dir_find_next_appendable_volume
Jul 9 14:08:04 backup bacula-sd: Server-sd: askdir.c:245 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 FindMedia=1 pool_name=Default
media_type=DVD
Jul 9 14:08:04 backup bacula-sd: Server-sd: askdir.c:165 <dird 1000 OK
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0
VolErrors=0 VolWrites=0 MaxVolBytes=2147483500 VolCapacityBytes=0
VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0
VolWriteTime=0 EndFile=0 EndBlock=0 VolParts=0 LabelType=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: reserve.c:301 Vol=DVD2006-07-09-4
not in use.
Jul 9 14:08:04 backup bacula-sd: Server-sd: reserve.c:146 New
Vol=DVD2006-07-09-4 dev="DVD" (/dev/hdc)
Jul 9 14:08:04 backup bacula-sd: Server-sd: mount.c:99 After find_next_append.
Vol=DVD2006-07-09-4 Slot=0 Parts=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: mount.c:119 autoload_dev returns 0
Jul 9 14:08:04 backup bacula-sd: Server-sd: mount.c:135 Ask=0 autochanger=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: mount.c:145 want
vol=DVD2006-07-09-4
Jul 9 14:08:04 backup bacula-sd: Server-sd: dev.c:276 open dev: tape=0
dev_name="DVD" (/dev/hdc) vol=DVD2006-07-09-4 mode=OPEN_READ_WRITE
Jul 9 14:08:04 backup bacula-sd: Server-sd: dev.c:282 call open_dvd_device
mode=OPEN_READ_WRITE
Jul 9 14:08:04 backup bacula-sd: Server-sd: dev.c:476 Enter: open_dvd_dev: DVD
dev= mode=OPEN_READ_WRITE
Jul 9 14:08:04 backup bacula-sd: Server-sd: dev.c:493 open_dvd_device:
num_parts=0, VolCatInfo.VolCatParts=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:78 Enter mount_dev
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:125 do_mount_dev:
cmd=/bin/mount -t iso9660 -o ro /dev/hdc /mnt/dvd mounted=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:117 ======= DVD mount=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:152 Device "DVD" (/dev/hdc)
cannot be mounted. ERR=mount: wrong fs type, bad option, bad superblock on
/dev/hdc,
Jul 9 14:08:04 backup bacula-sd: or too many mounted file systems
Jul 9 14:08:04 backup bacula-sd:
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:190 open_mounted_dev:
ignoring . in /mnt/dvd
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:190 open_mounted_dev:
ignoring .. in /mnt/dvd
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:182 open_mounted_dev: failed
to find suitable file in dir /mnt/dvd (dev="DVD" (/dev/hdc))
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:196 open_mounted_dev: got 0
files in the mount point (not counting ., .. and .keep)
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:206 ============ DVD mount=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:78 Enter mount_dev
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:125 do_mount_dev:
cmd=/bin/mount -t iso9660 -o ro /dev/hdc /mnt/dvd mounted=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:117 ======= DVD mount=0
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:152 Device "DVD" (/dev/hdc)
cannot be mounted. ERR=mount: wrong fs type, bad option, bad superblock on
/dev/hdc,
Jul 9 14:08:04 backup bacula-sd: or too many mounted file systems
Jul 9 14:08:04 backup bacula-sd:
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:190 open_mounted_dev:
ignoring . in /mnt/dvd
Jul 9 14:08:04 backup bacula-sd: Server-sd: dvd.c:190 open_mount
Jul 9 14:08:07 backup bacula-sd: ed_dev: ignoring .. in /mnt/dvd
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:182 open_mounted_dev: failed
to find suitable file in dir /mnt/dvd (dev="DVD" (/dev/hdc))
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:196 open_mounted_dev: got 0
files in the mount point (not counting ., .. and .keep)
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:206 ============ DVD mount=0
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:248 update_free_space_dev:
cmd=/etc/bacula/dvd-handler /dev/hdc free
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:257 Free space program run :
4689887232
Jul 9 14:08:07 backup bacula-sd: No Error reported.
Jul 9 14:08:07 backup bacula-sd:
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:292 update_free_space_dev:
free_space=4689887232 freespace_ok=1 free_space_errno=0 have_media=1
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:520 Could not mount device
"DVD" (/dev/hdc), this is not a problem (num_parts == 0), and have media.
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:539 open dev: DVD dev=
mode=OPEN_READ_WRITE part=0 npart=0 volcatnparts=0
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:541 openmode=2
OPEN_READ_WRITE
Jul 9 14:08:07 backup bacula-sd: Server-sd: dvd.c:69 Exit make_dvd_filename:
arch=/tmp/backup/DVD2006-07-09-4
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:558 mode=OPEN_READ_WRITE
open(/tmp/backup/DVD2006-07-09-4, 0x42, 0640)
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:582 after open fd=6
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:71 Enter read_volume_label
res=1 device="DVD" (/dev/hdc) vol=DVD2006-07-09-4 dev_Vol=*NULL*
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:627 rewind res=1 fd=6 "DVD"
(/dev/hdc)
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:138 Big if statement in
read_volume_label
Jul 9 14:08:07 backup bacula-sd: Server-sd: block.c:889 Full read() in
read_block_from_device() len=64512
Jul 9 14:08:07 backup bacula-sd: Server-sd: block.c:953 Read device got 0
bytes
at 0:0
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:143 Requested Volume
"DVD2006-07-09-4" on "DVD" (/dev/hdc) is not a Bacula labeled Volume, because:
ERR=block.c:958 Read zero bytes at 0:0 on device "DVD" (/dev/hdc).
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:627 rewind res=1 fd=6 "DVD"
(/dev/hdc)
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:245 return 3
Jul 9 14:08:07 backup bacula-sd: Server-sd: mount.c:182 Want
dirVol=DVD2006-07-09-4 dirStat=Append
Jul 9 14:08:07 backup bacula-sd: Server-sd: mount.c:272 Create volume label
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:299 write_volume_label()
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:305 Label type=0
Jul 9 14:08:07 backup bacula-sd: Server-sd: dev.c:627 rewind res=1 fd=6 "DVD"
(/dev/hdc)
Jul 9 14:08:07 backup bacula-sd: Server-sd: label.c:538 Start
create_volume_label()
Jul 9 14:08:07 backup bacula-sd: Server-sd: reserve.c:200 free_volume: no vol
on dev "DVD" (/dev/hdc)
Jul 9 14:08:07 backup bacula-sd: Server-sd: reserve.c:210 free_volume
DVD2006-07-09-4 dev="DVD" (/dev/hdc)
Jul 9 14:08:07 backup bacula-sd:
Jul 9 14:08:07 backup bacula-sd: Volume Label:
Jul 9 14:08:07 backup bacula-sd: Id : Bacula 1.0 immortal
Jul 9 14:08:07 backup bacula-sd: VerNo : 11
Jul 9 14:08:07 backup bacula-sd: VolName : DVD2006-07-09-4
Jul 9 14:08:07 backup bacula-sd: PrevVolName :
Jul 9 14:08:07 backup bacula-sd: VolFile : 0
Jul 9 14:08:07 backup bacula-sd: LabelType : PRE_LABEL
Jul 9 14:08:07 backup bacula-sd: LabelSize : 0
Jul 9 14:08:08 backup bacula-sd: PoolName : Default
Jul 9 14:08:08 backup bacula-sd: MediaType : DVD
Jul 9 14:08:08 backup bacula-sd: PoolType : Backup
Jul 9 14:08:08 backup bacula-sd: HostName : backup.lan
Jul 9 14:08:08 backup bacula-sd: Date label written: 09-Jul-2006 14:08
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:527 Created Vol label rec:
FI=PRE_LABEL len=165
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:341 Wrote label of 165
bytes to "DVD" (/dev/hdc)
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:344 Call
write_block_to_dev()
Jul 9 14:08:08 backup bacula-sd: Server-sd: block.c:430 binbuf=201
buf_len=64512
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:349 Wrote block to device
Jul 9 14:08:08 backup bacula-sd: Server-sd: dev.c:1510 weof_dev
Jul 9 14:08:08 backup bacula-sd:
Jul 9 14:08:08 backup bacula-sd: Volume Label:
Jul 9 14:08:08 backup bacula-sd: Id : Bacula 1.0 immortal
Jul 9 14:08:08 backup bacula-sd: VerNo : 11
Jul 9 14:08:08 backup bacula-sd: VolName : DVD2006-07-09-4
Jul 9 14:08:08 backup bacula-sd: PrevVolName :
Jul 9 14:08:08 backup bacula-sd: VolFile : 0
Jul 9 14:08:08 backup bacula-sd: LabelType : PRE_LABEL
Jul 9 14:08:08 backup bacula-sd: LabelSize : 0
Jul 9 14:08:08 backup bacula-sd: PoolName : Default
Jul 9 14:08:08 backup bacula-sd: MediaType : DVD
Jul 9 14:08:08 backup bacula-sd: PoolType : Backup
Jul 9 14:08:08 backup bacula-sd: HostName : backup.lan
Jul 9 14:08:08 backup bacula-sd: Date label written: 09-Jul-2006 14:08
Jul 9 14:08:08 backup bacula-sd: Server-sd: reserve.c:146 New
Vol=DVD2006-07-09-4 dev="DVD" (/dev/hdc)
Jul 9 14:08:08 backup bacula-sd: Server-sd: mount.c:280 dir_update_vol_info.
Set Append
Jul 9 14:08:08 backup bacula-sd: Server-sd: askdir.c:301 Update cat VolFiles=0
Jul 9 14:08:08 backup bacula-sd: Server-sd: askdir.c:320 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 UpdateMedia
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=0
VolErrors=0 VolWrites=0 MaxVolBytes=2147483500 EndTime=1152468487
VolStatus=Append Slot=0 relabel=1 InChanger=0 VolReadTime=0 VolWriteTime=0
VolParts=0
Jul 9 14:08:08 backup bacula-sd: Server-sd: askdir.c:165 <dird 1000 OK
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=0
VolErrors=0 VolWrites=0 MaxVolBytes=2147483500 VolCapacityBytes=0
VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0
VolWriteTime=0 EndFile=0 EndBlock=0 VolParts=0 LabelType=0
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:71 Enter read_volume_label
res=1 device="DVD" (/dev/hdc) vol=DVD2006-07-09-4 dev_Vol=DVD2006-07-09-4
Jul 9 14:08:08 backup bacula-sd: Server-sd: label.c:92 Leave read_volume_labe
Jul 9 14:08:15 backup bacula-sd: l() VOL_OK
Jul 9 14:08:15 backup bacula-sd: Server-sd: mount.c:182 Want
dirVol=DVD2006-07-09-4 dirStat=Append
Jul 9 14:08:15 backup bacula-sd: Server-sd: mount.c:189 Vol OK
name=DVD2006-07-09-4
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:384 set append found
freshly labeled volume. fd=6 dev=8c6b5d8
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:262 write Label in
write_volume_label_to_block()
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:527 Created Vol label rec:
FI=VOL_LABEL len=165
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:276 Wrote label of 165
bytes to block
Jul 9 14:08:15 backup bacula-sd: Server-sd: dev.c:627 rewind res=1 fd=6 "DVD"
(/dev/hdc)
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:425 Attempt to write to
device fd=6.
Jul 9 14:08:15 backup bacula-sd: Server-sd: block.c:430 binbuf=201
buf_len=64512
Jul 9 14:08:15 backup bacula-sd: Server-sd: label.c:449 dir_update_vol_info.
Set Append
Jul 9 14:08:15 backup bacula-sd: Server-sd: askdir.c:301 Update cat VolFiles=0
Jul 9 14:08:15 backup bacula-sd: Server-sd: askdir.c:320 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 UpdateMedia
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1
VolErrors=0 VolWrites=1 MaxVolBytes=2147483500 EndTime=1152468487
VolStatus=Append Slot=0 relabel=1 InChanger=0 VolReadTime=0 VolWriteTime=0
VolParts=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: askdir.c:165 <dird 1000 OK
VolName=DVD2006-07-09-4 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1
VolErrors=0 VolWrites=1 MaxVolBytes=2147483500 VolCapacityBytes=0
VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0
VolWriteTime=0 EndFile=0 EndBlock=0 VolParts=0 LabelType=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:465 OK from rewite vol
label.
Jul 9 14:08:16 backup bacula-sd: Server-sd: mount.c:407 set APPEND, normal
return from read_dev_for_append
Jul 9 14:08:16 backup bacula-sd: Server-sd: acquire.c:319 Dec reserve=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: append.c:81 Begin append
device="DVD" (/dev/hdc)
Jul 9 14:08:16 backup bacula-sd: Server-sd: append.c:86 Just after
acquire_device_for_append
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:636 session_label
record=8c6d648
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:692 Write sesson_label
record JobId=5 FI=SOS_LABEL SessId=1 Strm=5 len=182 remainder=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:696 Leave
write_session_label Block=200 File=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: append.c:254 Write session label
JobStatus=84
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:636 session_label
record=8c6d648
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:692 Write sesson_label
record JobId=5 FI=EOS_LABEL SessId=1 Strm=5 len=218 remainder=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: label.c:696 Leave
write_session_label Block=258248 File=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: append.c:273 back from
write_end_session_label()
Jul 9 14:08:16 backup bacula-sd: Server-sd: block.c:430 binbuf=61332
buf_len=64512
Jul 9 14:08:16 backup bacula-sd: Server-sd: dvd.c:706 Set VolCatParts=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: acquire.c:354 release_device
device
is disk
Jul 9 14:08:16 backup bacula-sd: Server-sd: acquire.c:376 There are 0 writers
in release_device
Jul 9 14:08:16 backup bacula-sd: Server-sd: acquire.c:378
dir_create_jobmedia_record. Release
Jul 9 14:08:16 backup bacula-sd: Server-sd: askdir.c:353 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 CreateJobMedia FirstIndex=1
LastIndex=14 StartFile=0 EndFile=0 StartBlock=201 EndBlock=319580 Copy=0 Strip=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: askdir.c:360 <dir: 1000 OK
CreateJobMedia
Jul 9 14:08:16 backup bacula-sd: Server-sd: dev.c:1510 weof_dev
Jul 9 14:08:16 backup bacula-sd: Server-sd: acquire.c:392 dir_update_vol_info.
Release0
Jul 9 14:08:16 backup bacula-sd: Server-sd: askdir.c:301 Update cat VolFiles=0
Jul 9 14:08:16 backup bacula-sd: Server-sd: askdir.c:320 >dird: CatReq
Job=BensLaptop_2_BC-DVD-RW.2006-07-09_14.07.52 UpdateMedia
VolName=DVD2006-07-09-4 VolJobs=1 VolFiles=0 VolBlocks=5 VolBytes=319381
VolMounts=1 VolErrors=0 VolWrites=6 MaxVolBytes=2147483500 EndTime=1152468494
VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=0
VolParts=0
Jul 9 14:08:17 backup bacula-sd: Server-sd: askdir.c:165 <dird 1000 OK
VolName=DVD2006-07-09-4 VolJobs=1 VolFiles=0 VolBlocks=5 VolBytes=319381
VolMounts=1 VolErrors=0 VolWrites=6 MaxVolBytes=2147483500 VolCapacityBytes=0
VolStatus=Used Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0
VolWriteTime=0 EndFile=0 EndBlock=319580 VolParts=0 LabelType=0
Jul 9 14:08:17 backup bacula-sd: Server-sd: dev.c:627 rewind res=0 fd=6 "DVD"
(/dev/hdc)
Jul 9 14:08:17 backup bacula-sd: Server-sd: reserve.c:224 free_volume
DVD2006-07-09-4 dev="DVD" (/dev/hdc)
Jul 9 14:08:17 backup bacula-sd: Server-sd: dev.c:1691 really close_dev "DVD"
(/dev/hdc)
Jul 9 14:08:17 backup bacula-sd: Server-sd: dvd.c:93 Enter unmount_dev
Jul 9 14:08:17 backup bacula-sd: Server-sd: reserve.c:200 free_volume: no vol
on dev "DVD" (/dev/hdc)
Jul 9 14:08:17 backup bacula-sd: Server-sd: append.c:307 return from
do_append_data() ok=1
Jul 9 14:08:17 backup bacula-sd: Server-sd: fd_cmds.c:135 <filed: append end
session 1
Jul 9 14:08:17 backup bacula-sd: Server-sd: fd_cmds.c:194 store<file: append
end session 1
Jul 9 14:08:17 backup bacula-sd: Server-sd: fd_cmds.c:135 <filed: append close
session 1
Jul 9 14:08:17 backup bacula-sd: Server-sd: fd_cmds.c:236 <filed: append close
session 1
Jul 9 14:08:17 backup bacula-sd: Server-sd: fd_cmds.c:243 >filed: 3000 OK
close
Stat
--
Benjamin Chambers, P.Eng.
ClarkConnect Account Manager
Point Clark Networks
1179 King Street West, Suite 211
Toronto, ON
Canada, M6K 3C5
Tel: +1.416.977.0574 Ext. 111
Fax: +1.416.946.1192
Email: [EMAIL PROTECTED]
-------------------------------------------------------------------------
Using Tomcat but need to do more? Need to support web services, security?
Get stuff done quickly with pre-integrated technology to make your job easier
Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
