I was not able to get the log to work for the windows client. However, I did
get the results attached from the Bacula server. Can you see why is causing
the issue? The first half of the file is from the Bacula server being
backed up with success. The second half is the windows XP machine failing.
Thanks,
mike
On Fri, Mar 11, 2011 at 11:33 AM, John Drescher <dresche...@gmail.com>wrote:
> On Fri, Mar 11, 2011 at 12:28 PM, Mike Hendrie <m...@hendrienet.com>
> wrote:
> > Ubuntu firewall is: inactive
> > XP firewall is: disabled
> >
> > No localhost or 127.0.0.1 settings in the following files:
> >
> > bacula-dir.conf
> > bacula-sd.conf
> > bacula-fd.conf
> >
> >
> > In the files I am using "bacula" in place of the server DNS: 10.2.1.98
> and
> > ""dragon" in place of the DNS:10.2.1.97. There are no, non-commented out
> > (#), localhost or 127.0.0.1 IPs in the file.
> >
>
> Run both the bacula-fd from the command line with -d 100 and bacula-sd
> from the shell with -d 100 to capture the logs on both systems to try
> to determine what is happening. Remember to specify the -c parameter
> to both indicating the location of the configuration file. Also make
> sure you kill the services for both so that you can run them in the
> terminal..
>
> John
>
==================================================================
==== Bacula server backup = Success results below ==================
==================================================================
w14219@bacula:~$ sudo bacula-sd -d 100
bacula-sd: stored_conf.c:698-0 Inserting director res: bacula-mon
bacula-sd: jcr.c:138-0 read_last_jobs seek to 192
bacula-sd: jcr.c:145-0 Read num_items=10
w14219@bacula:~$ bacula-sd: pythonlib.c:104-0 No script dir. prog=SDStartUp
bacula-sd: jcr.c:611-0 setting my_thread_stuffs 0x0 => 0x7f001aef6710
bacula-sd: stored.c:534-0 calling init_dev /bacula/backups
bacula-sd: bnet_server.c:96-0 Addresses host[ipv4:10.2.1.98:9103]
bacula-sd: dev.c:284-0 init_dev: tape=0 dev_name=/bacula/backups
bacula-sd: stored.c:536-0 SD init done /bacula/backups
bacula-sd: bnet.c:669-0 who=client host=10.2.1.98 port=36643
bacula-sd: jcr.c:611-0 setting my_thread_stuffs 0x0 => 0x7f001aef6710
bacula-sd: cram-md5.c:73-0 send: auth cram-md5
<1187728799.1299873051@bacula-sd> ssl=0
bacula-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<1507929211.1299873051@bacula-dir> ssl=0
bacula-sd: cram-md5.c:152-0 sending resp to challenge: jn/fn8/9lQ+/VDU296+ZvA
bacula-sd: dircmd.c:211-0 Message channel init completed.
bacula-sd: job.c:96-0 <dird: JobId=31 job=BackupClient1.2011-03-11_13.50.49_13
job_name=BackupClient1 client_name=bacula-fd type=66 level=73 FileSet=FullSet
NoAttr=0 SpoolAttr=0 FileSetMD5=g5/H960Gqm+FqSkC9EEL9A SpoolData=0
WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=0
bacula-sd: job.c:170-31 >dird jid=31: 3000 OK Job SDid=1 SDtime=1299873035
Authorization=IDAH-MOIN-OCAK-LCKK-PPAO-BKHB-NHJG-MPEO
bacula-sd: pythonlib.c:227-31 No startup module.
bacula-sd: askdir.c:277-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 FindMedia=1 pool_name=File
media_type=File
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=10 VolFiles=0
VolBlocks=321 VolBytes=20205336 VolMounts=10 VolErrors=0 VolWrites=322
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383690 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: askdir.c:286-31 Call reserve_volume. Vol=vol002
bacula-sd: askdir.c:293-31 dir_find_next_appendable_volume return true.
vol=vol002
bacula-sd: job.c:203-31 BackupClient1.2011-03-11_13.50.49_13 waiting 1800 sec
for FD to contact SD key=IDAH-MOIN-OCAK-LCKK-PPAO-BKHB-NHJG-MPEO
bacula-sd: bnet.c:669-0 who=client host=10.2.1.98 port=36643
bacula-sd: dircmd.c:180-0 Hello Start Job BackupClient1.2011-03-11_13.50.49_13
bacula-sd: job.c:251-0 Found Job BackupClient1.2011-03-11_13.50.49_13
bacula-sd: cram-md5.c:73-0 send: auth cram-md5 <517269924.1299873051@bacula-sd>
ssl=0
bacula-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<256887317.1299873051@bacula-fd> ssl=0
bacula-sd: cram-md5.c:152-0 sending resp to challenge: lw/1o2tiWk/OnTUfkw+ilB
bacula-sd: job.c:274-0 OK Authentication jid=31 Job
BackupClient1.2011-03-11_13.50.49_13
bacula-sd: job.c:218-31 Auth=1 canceled=0 errstat=0
bacula-sd: job.c:224-31 Running job BackupClient1.2011-03-11_13.50.49_13
bacula-sd: append.c:72-31 Start append data. res=1
bacula-sd: acquire.c:364-31 acquire_append device is disk
bacula-sd: askdir.c:231-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 GetVolInfo VolName=vol002 write=1
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=10 VolFiles=0
VolBlocks=321 VolBytes=20205336 VolMounts=10 VolErrors=0 VolWrites=322
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383690 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: autochanger.c:120-31 Device "FileStorage" (/bacula/backups) is not
an autochanger
bacula-sd: dev.c:361-31 open dev: type=1 dev_name="FileStorage"
(/bacula/backups) vol=vol002 mode=OPEN_READ_WRITE
bacula-sd: dev.c:370-31 call open_file_device mode=OPEN_READ_WRITE
bacula-sd: dev.c:543-31 open disk: mode=OPEN_READ_WRITE
open(/bacula/backups/vol002, 0x2, 0640)
bacula-sd: dev.c:558-31 open dev: disk fd=6 opened, part=0/0, part_size=0
bacula-sd: dev.c:374-31 preserve=0x0 fd=6
bacula-sd: label.c:81-31 Enter read_volume_label res=1 device="FileStorage"
(/bacula/backups) vol=vol002 dev_Vol=*NULL*
Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : vol002
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 151
PoolName : File
MediaType : File
PoolType : Backup
HostName : bacula
Date label written: 03-Mar-2011 14:57
bacula-sd: label.c:236-31 Call reserve_volume=vol002
bacula-sd: mount.c:250-31 Do not have volcatinfo
bacula-sd: askdir.c:231-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 GetVolInfo VolName=vol002 write=1
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=10 VolFiles=0
VolBlocks=321 VolBytes=20205336 VolMounts=10 VolErrors=0 VolWrites=322
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383690 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: mount.c:283-31 Device previously written, moving to end of data.
Expect 20205336 bytes
bacula-sd: dev.c:896-31 Enter eod
bacula-sd: askdir.c:346-31 Update cat VolBytes=20205336
bacula-sd: askdir.c:369-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 UpdateMedia VolName=vol002 VolJobs=10
VolFiles=0 VolBlocks=321 VolBytes=20205336 VolMounts=11 VolErrors=0
VolWrites=322 MaxVolBytes=53687091200 EndTime=1299873051 VolStatus=Append
Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=383690
VolFirstWritten=0 VolParts=0
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=10 VolFiles=0
VolBlocks=321 VolBytes=20205336 VolMounts=11 VolErrors=0 VolWrites=322
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383690 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: acquire.c:424-31 === nwriters=1 nres=1 vcatjob=11 dev="FileStorage"
(/bacula/backups)
bacula-sd: askdir.c:346-31 Update cat VolBytes=20205336
bacula-sd: askdir.c:369-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 UpdateMedia VolName=vol002 VolJobs=11
VolFiles=0 VolBlocks=321 VolBytes=20205336 VolMounts=11 VolErrors=0
VolWrites=322 MaxVolBytes=53687091200 EndTime=1299873051 VolStatus=Append
Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=383690
VolFirstWritten=0 VolParts=0
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=11 VolFiles=0
VolBlocks=321 VolBytes=20205336 VolMounts=11 VolErrors=0 VolWrites=322
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383690 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: append.c:93-31 Begin append device="FileStorage" (/bacula/backups)
bacula-sd: append.c:98-31 Just after acquire_device_for_append
bacula-sd: append.c:295-31 back from write_end_session_label()
bacula-sd: acquire.c:460-31 release_device device "FileStorage"
(/bacula/backups) is disk
bacula-sd: acquire.c:483-31 There are 0 writers in release_device
bacula-sd: askdir.c:405-31 JobMedia FI=0 StartBlock!=0 record suppressed
bacula-sd: askdir.c:346-31 Update cat VolBytes=20205736
bacula-sd: askdir.c:369-31 >dird CatReq
Job=BackupClient1.2011-03-11_13.50.49_13 UpdateMedia VolName=vol002 VolJobs=11
VolFiles=0 VolBlocks=322 VolBytes=20205736 VolMounts=11 VolErrors=0
VolWrites=323 MaxVolBytes=53687091200 EndTime=1299873051 VolStatus=Append
Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=383734
VolFirstWritten=0 VolParts=0
bacula-sd: askdir.c:182-31 <dird 1000 OK VolName=vol002 VolJobs=11 VolFiles=0
VolBlocks=322 VolBytes=20205736 VolMounts=11 VolErrors=0 VolWrites=323
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383734 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-31 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: acquire.c:517-31 0 writers, 0 reserve, dev="FileStorage"
(/bacula/backups)
bacula-sd: dev.c:1925-31 close_dev "FileStorage" (/bacula/backups)
bacula-sd: dev.c:2122-31 Enter unmount
bacula-sd: dev.c:1914-31 Clear volhdr vol=vol002
bacula-sd: acquire.c:554-31 JobId=31 broadcast wait_device_release at
11-Mar-2011 13:50:51
bacula-sd: acquire.c:575-31 ===== Device "FileStorage" (/bacula/backups)
released by JobId=31
bacula-sd: spool.c:672-31 Commit attributes at 11-Mar-2011 13:50:51
bacula-sd: append.c:339-31 return from do_append_data() ok=1
bacula-sd: pythonlib.c:227-31 No startup module.
bacula-sd: pythonlib.c:227-31 No startup module.
bacula-sd: jcr.c:181-31 write_last_jobs seek to 192
==================================================================
========= Windows backup failed backup======= below are the results
==================================================================
w14219@bacula:~$ sudo bacula-sd -d 100
bacula-sd: stored_conf.c:698-0 Inserting director res: bacula-mon
w14219@bacula:~$ bacula-sd: jcr.c:138-0 read_last_jobs seek to 192
bacula-sd: jcr.c:145-0 Read num_items=10
bacula-sd: pythonlib.c:104-0 No script dir. prog=SDStartUp
bacula-sd: jcr.c:611-0 setting my_thread_stuffs 0x0 => 0x7fe5d09ad710
bacula-sd: bnet_server.c:96-0 Addresses host[ipv4:10.2.1.98:9103]
bacula-sd: stored.c:534-0 calling init_dev /bacula/backups
bacula-sd: dev.c:284-0 init_dev: tape=0 dev_name=/bacula/backups
bacula-sd: stored.c:536-0 SD init done /bacula/backups
bacula-sd: bnet.c:669-0 who=client host=10.2.1.98 port=36643
bacula-sd: jcr.c:611-0 setting my_thread_stuffs 0x0 => 0x7fe5d09ad710
bacula-sd: cram-md5.c:73-0 send: auth cram-md5 <150068997.1299873161@bacula-sd>
ssl=0
bacula-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<2004463071.1299873161@bacula-dir> ssl=0
bacula-sd: cram-md5.c:152-0 sending resp to challenge: t8dph7FZh6/TXGI5u9wmKC
bacula-sd: dircmd.c:211-0 Message channel init completed.
bacula-sd: job.c:96-0 <dird: JobId=33 job=dragon.2011-03-11_13.52.39_16
job_name=dragon client_name=dragon-fd type=66 level=70 FileSet=WindowsBackup
NoAttr=0 SpoolAttr=0 FileSetMD5=kH1y8hR2G4/Yj7EgblJmkA SpoolData=0
WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=0
bacula-sd: job.c:170-33 >dird jid=33: 3000 OK Job SDid=1 SDtime=1299873148
Authorization=HHNF-NHCF-BJEI-DLNE-PHHI-DABN-OAKJ-JKAJ
bacula-sd: pythonlib.c:227-33 No startup module.
bacula-sd: askdir.c:277-33 >dird CatReq Job=dragon.2011-03-11_13.52.39_16
FindMedia=1 pool_name=File media_type=File
bacula-sd: askdir.c:182-33 <dird 1000 OK VolName=vol002 VolJobs=11 VolFiles=0
VolBlocks=322 VolBytes=20205736 VolMounts=11 VolErrors=0 VolWrites=323
MaxVolBytes=53687091200 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0
MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=383734 EndFile=0
EndBlock=20205335 VolParts=0 LabelType=0 MediaId=2
bacula-sd: askdir.c:206-33 do_reqest_vol_info return true slot=0 Volume=vol002
bacula-sd: askdir.c:286-33 Call reserve_volume. Vol=vol002
bacula-sd: askdir.c:293-33 dir_find_next_appendable_volume return true.
vol=vol002
bacula-sd: job.c:203-33 dragon.2011-03-11_13.52.39_16 waiting 1800 sec for FD
to contact SD key=HHNF-NHCF-BJEI-DLNE-PHHI-DABN-OAKJ-JKAJ
bacula-sd: bnet.c:669-0 who=client host=10.2.1.98 port=36643
bacula-sd: jcr.c:611-0 setting my_thread_stuffs 0x0 => 0x7fe5cf9ab710
bacula-sd: cram-md5.c:73-0 send: auth cram-md5
<1641997882.1299873163@bacula-sd> ssl=0
bacula-sd: cram-md5.c:133-0 cram-get received: auth cram-md5
<113938235.1299873163@bacula-dir> ssl=0
bacula-sd: cram-md5.c:152-0 sending resp to challenge: sl5bw3BaV5/JY1MV1A/OuA
bacula-sd: dircmd.c:211-0 Message channel init completed.
bacula-sd: job.c:218-33 Auth=0 canceled=1 errstat=0
bacula-sd: pythonlib.c:227-33 No startup module.
bacula-sd: jcr.c:181-0 write_last_jobs seek to 192
bacula-sd: pythonlib.c:227-0 No startup module.
------------------------------------------------------------------------------
Colocation vs. Managed Hosting
A question and answer guide to determining the best fit
for your organization - today and in the future.
http://p.sf.net/sfu/internap-sfd2d
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
