Is Virtual Autochanger for Multiple Disks writing still working?

classic Classic list List threaded Threaded
4 messages Options
Reply | Threaded
Open this post in threaded view
|

Is Virtual Autochanger for Multiple Disks writing still working?

Heitor Faria
Hello, Bacula Users,

I'm setting up an environment according to the "2.2 Using a Virtual Autochanger" section of: http://blog.bacula.org/whitepapers/CommunityDiskBackup.pdf
Using: 7.4.4 (20 September 2016) x86_64-unknown-linux-gnu debian 7.7 version, built from source.
I remember it used to work in prior versions, but today I'm not able to run concurrent Jobs to both disks. The second backup job stalls with the following status: "BackupArquivosBacula is waiting on Storage Disco".
Bellow are the configurations and the bacula-sd -d 100 output of both job executions:

# bacula-sd.conf

Autochanger {
Name = FileChgr1
Device = Disco1, Disco2
Changer Command = "/dev/null"
Changer Device = /dev/null
}

Device {
Name = "Disco1"
Drive Index = 0
Media Type = File1
Archive Device = /backup1
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

Device {
Name = "Disco2"
Drive Index = 1
Media Type = File2
Archive Device = /backup2
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

# bacula-dir.conf

Storage {
Name = "Disco"
Address = 192.168.12.6
SDPort = 9103
Password = ""
Device = FileChgr1
Media Type = File1
Maximum Concurrent Jobs = 10
Autochanger = yes
}


# bacula-sd -d 100

SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2991 === nwriters=1 nres=1 vcatjob=11 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2991 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2991 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2991 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: bnet.c:566-0 who=client host=192.168.12.6 port=9103
SRV-BACKUP-sd: dircmd.c:178-0 Got a DIR connection at 17-Jan-2017 12:55:35
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <255618738.1484664935@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1751508803.1484664935@SRV-BACKUP-dir> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: /Q/kimxPmVwym5/GxW+kzD
SRV-BACKUP-sd: dircmd.c:204-0 Message channel init completed.
SRV-BACKUP-sd: job.c:78-0 <dird: JobId=2992 job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 job_name=Backup-Aroeira-fd client_name=AROEIRA-fd type=66 level=70 FileSet=servidores_linux_set NoAttr=0 SpoolAttr=1 FileSetMD5=BD+1l4wAW4+zoXp991gLrC SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy
SRV-BACKUP-sd: job.c:100-0 rerunning=0 VolSesId=0 VolSesTime=0
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1 LI=1493 StartBlock=420548098 EndBlock=1420484028 Wrote
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14305385917 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 EndTime=1484664991 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: block_util.c:673-2991 dir_update_volume_info max file size -- OK
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1493 LI=3245 StartBlock=1420484029 EndBlock=1605729006 Wrote
SRV-BACKUP-sd: acquire.c:490-2991 release_device device "Disco1" (/backup1) is disk
SRV-BACKUP-sd: acquire.c:514-2991 There are 0 writers in release_device
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:550-2991 0 writers, 0 reserve, dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:732-2991 close_dev vol=Diaria-Disco-4 fd=8 dev=110c4c8 dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:826-2991 Enter unmount
SRV-BACKUP-sd: dev.c:525-2991 Clear volhdr vol=Diaria-Disco-4
SRV-BACKUP-sd: acquire.c:591-2991 JobId=2991 broadcast wait_device_release at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:611-2991 Device "Disco1" (/backup1) released by JobId=2991
SRV-BACKUP-sd: spool.c:674-2991 Commit attributes at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: job.c:188-2992 sd_calls_client=0 sd_client=0
SRV-BACKUP-sd: job.c:210-2992 Backup-Aroeira-fd.2017-01-17_12.55.32_28 waiting 1800 sec for FD to contact SD key=PGBJ-MOCK-BPHN-AGIG-NAIK-EPJF-IPCF-IFIO
SRV-BACKUP-sd: bnet.c:566-0 who=client host=200.137.236.2 port=9103
SRV-BACKUP-sd: hello.c:134-0 Conn: Hello Start Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:154-0 Found Client Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:165-0 fd_version=0 sd_version=0
SRV-BACKUP-sd: authenticate.c:157-0 Challenge FD
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <1130532902.1484665001@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: authenticate.c:161-0 Respond to FD challenge
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <16476019.1484664933@AROEIRA-fd> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: J6+zB6+We/sm23+fWnwqqD
SRV-BACKUP-sd: hello.c:182-0 OK Authentication jid=2992 Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:199-0 === Auth OK, unblock Job Backup-Aroeira-fd.2017-01-17_12.55.32_28 jid=2992 sd_ver=0
SRV-BACKUP-sd: job.c:228-2992 === Auth=2992 jid=1 canceled=0 errstat=0
SRV-BACKUP-sd: fd_cmds.c:125-2992 ==== JobType=B run_job=2992 sd_client=0
SRV-BACKUP-sd: fd_cmds.c:149-2992 Do: do_client_commands
SRV-BACKUP-sd: append.c:86-2992 Start append data. res=1
SRV-BACKUP-sd: acquire.c:384-2992 acquire_append device is disk
SRV-BACKUP-sd: mount.c:71-2992 Enter mount_next_volume(release=0) dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:83-2992 mount_next_vol retry=0
SRV-BACKUP-sd: mount.c:638-2992 No swap_dev set. dev->vol=1126f08
SRV-BACKUP-sd: mount.c:118-2992 After find_a_volume. Vol=Diaria-Disco-4 Slot=0 VolType=1
SRV-BACKUP-sd: autochanger.c:106-2992 Device "Disco1" (/backup1) is not an autochanger
SRV-BACKUP-sd: mount.c:146-2992 autoload_dev returns 0
SRV-BACKUP-sd: mount.c:162-2992 Ask=0 autochanger=0
SRV-BACKUP-sd: mount.c:177-2992 want vol=Diaria-Disco-4 devvol= dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:191-2992 Try open Vol=Diaria-Disco-4
SRV-BACKUP-sd: dev.c:387-2992 call open_file_device mode=OPEN_READ_WRITE
SRV-BACKUP-sd: file_dev.c:179-2992 open disk: mode=OPEN_READ_WRITE open(/backup1/Diaria-Disco-4, 0x2, 0640)
SRV-BACKUP-sd: file_dev.c:188-2992 Did open(/backup1/Diaria-Disco-4,OPEN_READ_WRITE,0640)
SRV-BACKUP-sd: file_dev.c:204-2992 open dev: disk fd=11 opened
SRV-BACKUP-sd: dev.c:391-2992 preserve=0x0 fd=11
SRV-BACKUP-sd: dev.c:394-2992 open dev: fd=11 dev=110c8a6 dcr=110c4c8 vol= type=1 dev_name="Disco1" (/backup1) mode=OPEN_READ_WRITE
SRV-BACKUP-sd: label.c:75-2992 Enter read_volume_label res=1 device="Disco1" (/backup1) vol=Diaria-Disco-4 dev_Vol=*NULL*
SRV-BACKUP-sd: label.c:879-2992 Enter: bool unser_volume_label(DEVICE*, DEV_RECORD*)
SRV-BACKUP-sd: label.c:896-2992 reclen=180 recdata=Bacula 1.0 immortal

SRV-BACKUP-sd: label.c:898-2992 reclen=180 recdata=Bacula 1.0 immortal


Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:929-2992 Leave: bool unser_volume_label(DEVICE*, DEV_RECORD*)

Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:232-2992 Call reserve_volume=Diaria-Disco-4
SRV-BACKUP-sd: mount.c:261-2992 Do not have volcatinfo
SRV-BACKUP-sd: mount.c:295-2992 Device previously written, moving to end of data. Expect 14490630895 bytes
SRV-BACKUP-sd: tape_dev.c:264-2992 Enter: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: tape_dev.c:294-2992 Leave: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2992 === nwriters=1 nres=1 vcatjob=12 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665002 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2992 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2992 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2992 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2992 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2992 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2992 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2992 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: spool.c:761-2991 Close attr spool file at 17-Jan-2017 12:56:50
SRV-BACKUP-sd: append.c:358-2991 return from do_append_data() ok=1
SRV-BACKUP-sd: fd_cmds.c:161-2991 ==== 3099 Job BackupArquivosBacula.2017-01-17_12.55.29_27 end JobStatus=84 JobFiles=3245 JobBytes=1183712749 JobErrors=0
SRV-BACKUP-sd: jcr.c:174-2991 write_last_jobs seek to 192

Regards,
--
===========================================================================
Heitor Medrado de Faria  | Bacula do Brasil
• Não seja tarifado pelo tamanho dos seus backups, conheça o Bacula Enterprise: http://www.bacula.com.br/enterprise/
 Ministro treinamento e implementação in-company do Bacula Community: http://www.bacula.com.br/in-company/
(61) 98268-4220 | www.bacula.com.br 
============================================================================
Indicamos também as capacitações complementares:
 Shell básico e Programação em Shell com Julio Neves.
 Zabbix com Adail Host.
============================================================================

------------------------------------------------------------------------------
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
[hidden email]
https://lists.sourceforge.net/lists/listinfo/bacula-users
Reply | Threaded
Open this post in threaded view
|

Re: Is Virtual Autochanger for Multiple Disks writing still working?

Kern Sibbald
Hello Heitor,

Bacula is quite literal (i.e. any spurious characters can cause problems).

Please try replacing your:

Changer Command = "/dev/null"

with either:

Changer Command = /dev/null

or

Changer Command = ""

Best regards,
Kern


On 01/17/2017 04:02 PM, Heitor Faria wrote:
Hello, Bacula Users,

I'm setting up an environment according to the "2.2 Using a Virtual Autochanger" section of: http://blog.bacula.org/whitepapers/CommunityDiskBackup.pdf
Using: 7.4.4 (20 September 2016) x86_64-unknown-linux-gnu debian 7.7 version, built from source.
I remember it used to work in prior versions, but today I'm not able to run concurrent Jobs to both disks. The second backup job stalls with the following status: "BackupArquivosBacula is waiting on Storage Disco".
Bellow are the configurations and the bacula-sd -d 100 output of both job executions:

# bacula-sd.conf

Autochanger {
Name = FileChgr1
Device = Disco1, Disco2
Changer Command = "/dev/null"
Changer Device = /dev/null
}

Device {
Name = "Disco1"
Drive Index = 0
Media Type = File1
Archive Device = /backup1
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

Device {
Name = "Disco2"
Drive Index = 1
Media Type = File2
Archive Device = /backup2
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

# bacula-dir.conf

Storage {
Name = "Disco"
Address = 192.168.12.6
SDPort = 9103
Password = ""
Device = FileChgr1
Media Type = File1
Maximum Concurrent Jobs = 10
Autochanger = yes
}


# bacula-sd -d 100

SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2991 === nwriters=1 nres=1 vcatjob=11 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2991 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2991 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2991 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: bnet.c:566-0 who=client host=192.168.12.6 port=9103
SRV-BACKUP-sd: dircmd.c:178-0 Got a DIR connection at 17-Jan-2017 12:55:35
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <255618738.1484664935@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1751508803.1484664935@SRV-BACKUP-dir> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: /Q/kimxPmVwym5/GxW+kzD
SRV-BACKUP-sd: dircmd.c:204-0 Message channel init completed.
SRV-BACKUP-sd: job.c:78-0 <dird: JobId=2992 job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 job_name=Backup-Aroeira-fd client_name=AROEIRA-fd type=66 level=70 FileSet=servidores_linux_set NoAttr=0 SpoolAttr=1 FileSetMD5=BD+1l4wAW4+zoXp991gLrC SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy
SRV-BACKUP-sd: job.c:100-0 rerunning=0 VolSesId=0 VolSesTime=0
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1 LI=1493 StartBlock=420548098 EndBlock=1420484028 Wrote
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14305385917 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 EndTime=1484664991 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: block_util.c:673-2991 dir_update_volume_info max file size -- OK
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1493 LI=3245 StartBlock=1420484029 EndBlock=1605729006 Wrote
SRV-BACKUP-sd: acquire.c:490-2991 release_device device "Disco1" (/backup1) is disk
SRV-BACKUP-sd: acquire.c:514-2991 There are 0 writers in release_device
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:550-2991 0 writers, 0 reserve, dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:732-2991 close_dev vol=Diaria-Disco-4 fd=8 dev=110c4c8 dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:826-2991 Enter unmount
SRV-BACKUP-sd: dev.c:525-2991 Clear volhdr vol=Diaria-Disco-4
SRV-BACKUP-sd: acquire.c:591-2991 JobId=2991 broadcast wait_device_release at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:611-2991 Device "Disco1" (/backup1) released by JobId=2991
SRV-BACKUP-sd: spool.c:674-2991 Commit attributes at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: job.c:188-2992 sd_calls_client=0 sd_client=0
SRV-BACKUP-sd: job.c:210-2992 Backup-Aroeira-fd.2017-01-17_12.55.32_28 waiting 1800 sec for FD to contact SD key=PGBJ-MOCK-BPHN-AGIG-NAIK-EPJF-IPCF-IFIO
SRV-BACKUP-sd: bnet.c:566-0 who=client host=200.137.236.2 port=9103
SRV-BACKUP-sd: hello.c:134-0 Conn: Hello Start Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:154-0 Found Client Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:165-0 fd_version=0 sd_version=0
SRV-BACKUP-sd: authenticate.c:157-0 Challenge FD
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <1130532902.1484665001@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: authenticate.c:161-0 Respond to FD challenge
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <16476019.1484664933@AROEIRA-fd> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: J6+zB6+We/sm23+fWnwqqD
SRV-BACKUP-sd: hello.c:182-0 OK Authentication jid=2992 Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:199-0 === Auth OK, unblock Job Backup-Aroeira-fd.2017-01-17_12.55.32_28 jid=2992 sd_ver=0
SRV-BACKUP-sd: job.c:228-2992 === Auth=2992 jid=1 canceled=0 errstat=0
SRV-BACKUP-sd: fd_cmds.c:125-2992 ==== JobType=B run_job=2992 sd_client=0
SRV-BACKUP-sd: fd_cmds.c:149-2992 Do: do_client_commands
SRV-BACKUP-sd: append.c:86-2992 Start append data. res=1
SRV-BACKUP-sd: acquire.c:384-2992 acquire_append device is disk
SRV-BACKUP-sd: mount.c:71-2992 Enter mount_next_volume(release=0) dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:83-2992 mount_next_vol retry=0
SRV-BACKUP-sd: mount.c:638-2992 No swap_dev set. dev->vol=1126f08
SRV-BACKUP-sd: mount.c:118-2992 After find_a_volume. Vol=Diaria-Disco-4 Slot=0 VolType=1
SRV-BACKUP-sd: autochanger.c:106-2992 Device "Disco1" (/backup1) is not an autochanger
SRV-BACKUP-sd: mount.c:146-2992 autoload_dev returns 0
SRV-BACKUP-sd: mount.c:162-2992 Ask=0 autochanger=0
SRV-BACKUP-sd: mount.c:177-2992 want vol=Diaria-Disco-4 devvol= dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:191-2992 Try open Vol=Diaria-Disco-4
SRV-BACKUP-sd: dev.c:387-2992 call open_file_device mode=OPEN_READ_WRITE
SRV-BACKUP-sd: file_dev.c:179-2992 open disk: mode=OPEN_READ_WRITE open(/backup1/Diaria-Disco-4, 0x2, 0640)
SRV-BACKUP-sd: file_dev.c:188-2992 Did open(/backup1/Diaria-Disco-4,OPEN_READ_WRITE,0640)
SRV-BACKUP-sd: file_dev.c:204-2992 open dev: disk fd=11 opened
SRV-BACKUP-sd: dev.c:391-2992 preserve=0x0 fd=11
SRV-BACKUP-sd: dev.c:394-2992 open dev: fd=11 dev=110c8a6 dcr=110c4c8 vol= type=1 dev_name="Disco1" (/backup1) mode=OPEN_READ_WRITE
SRV-BACKUP-sd: label.c:75-2992 Enter read_volume_label res=1 device="Disco1" (/backup1) vol=Diaria-Disco-4 dev_Vol=*NULL*
SRV-BACKUP-sd: label.c:879-2992 Enter: bool unser_volume_label(DEVICE*, DEV_RECORD*)
SRV-BACKUP-sd: label.c:896-2992 reclen=180 recdata=Bacula 1.0 immortal

SRV-BACKUP-sd: label.c:898-2992 reclen=180 recdata=Bacula 1.0 immortal


Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:929-2992 Leave: bool unser_volume_label(DEVICE*, DEV_RECORD*)

Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:232-2992 Call reserve_volume=Diaria-Disco-4
SRV-BACKUP-sd: mount.c:261-2992 Do not have volcatinfo
SRV-BACKUP-sd: mount.c:295-2992 Device previously written, moving to end of data. Expect 14490630895 bytes
SRV-BACKUP-sd: tape_dev.c:264-2992 Enter: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: tape_dev.c:294-2992 Leave: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2992 === nwriters=1 nres=1 vcatjob=12 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665002 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2992 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2992 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2992 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2992 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2992 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2992 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2992 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: spool.c:761-2991 Close attr spool file at 17-Jan-2017 12:56:50
SRV-BACKUP-sd: append.c:358-2991 return from do_append_data() ok=1
SRV-BACKUP-sd: fd_cmds.c:161-2991 ==== 3099 Job BackupArquivosBacula.2017-01-17_12.55.29_27 end JobStatus=84 JobFiles=3245 JobBytes=1183712749 JobErrors=0
SRV-BACKUP-sd: jcr.c:174-2991 write_last_jobs seek to 192

Regards,
--
===========================================================================
Heitor Medrado de Faria  | Bacula do Brasil
• Não seja tarifado pelo tamanho dos seus backups, conheça o Bacula Enterprise: http://www.bacula.com.br/enterprise/
 Ministro treinamento e implementação in-company do Bacula Community: http://www.bacula.com.br/in-company/
(61) 98268-4220 | www.bacula.com.br 
============================================================================
Indicamos também as capacitações complementares:
 Shell básico e Programação em Shell com Julio Neves.
 Zabbix com Adail Host.
============================================================================


------------------------------------------------------------------------------
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
[hidden email]
https://lists.sourceforge.net/lists/listinfo/bacula-users



------------------------------------------------------------------------------
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
[hidden email]
https://lists.sourceforge.net/lists/listinfo/bacula-users
Reply | Threaded
Open this post in threaded view
|

Re: Is Virtual Autochanger for Multiple Disks writing still working?

Heitor Faria
Hello Heitor,
Hello, Kern! Thanks for the quick reply.
Bacula is quite literal (i.e. any spurious characters can cause problems).
Please try replacing your:

Changer Command = "/dev/null"

with either:

Changer Command = /dev/null

or

Changer Command = ""
I tried both ways. Now, with ChangerCommand = "", it seems to look for the other disk. But it always attach the first one:

SRV-BACKUP-sd: askdir.c:382-2999 Update cat VolBytes=20618439384 Status=Append Vol=Diaria-Disco-8
SRV-BACKUP-sd: askdir.c:413-2999 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_15.08.05_45 UpdateMedia VolName=Diaria-Disco-8 VolJobs=6 VolFiles=4 VolBlocks=299249 VolBytes=20618439384 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=6 VolErrors=0 VolWrites=299250 MaxVolBytes=21474836480 EndTime=1484672887 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=81269358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2999 get_volume_info() 1000 OK VolName=Diaria-Disco-8 VolJobs=6 VolFiles=4 VolBlocks=299249 VolBytes=20618439384 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=6 VolErrors=0 VolWrites=299250 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=81269358 EndFile=4 EndBlock=3438570200 VolType=1 LabelType=0 MediaId=114 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2999 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2999 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2999 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2999 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2999 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2999 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2999 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: bnet.c:566-0 who=client host=192.168.12.6 port=9103
SRV-BACKUP-sd: dircmd.c:178-0 Got a DIR connection at 17-Jan-2017 15:08:12
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <782253950.1484672892@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1662626839.1484672892@SRV-BACKUP-dir> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: e3/N5Q8zd9MVP0/We8/LYC
SRV-BACKUP-sd: dircmd.c:204-0 Message channel init completed.
SRV-BACKUP-sd: job.c:78-0 <dird: JobId=3000 job=BackupArquivosBacula.2017-01-17_15.08.10_46 job_name=BackupArquivosBacula client_name=SRV-BACKUP-fd type=66 level=70 FileSet=servidores_linux_set NoAttr=0 SpoolAttr=1 FileSetMD5=BD+1l4wAW4+zoXp991gLrC SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy
SRV-BACKUP-sd: job.c:100-0 rerunning=0 VolSesId=0 VolSesTime=0
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-3000 Detach 0x12596a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-3000 Attach 0x12596a8 to dev "Disco1" (/backup1)
Best regards,
Regards,
Kern


On 01/17/2017 04:02 PM, Heitor Faria wrote:
Hello, Bacula Users,

I'm setting up an environment according to the "2.2 Using a Virtual Autochanger" section of: http://blog.bacula.org/whitepapers/CommunityDiskBackup.pdf
Using: 7.4.4 (20 September 2016) x86_64-unknown-linux-gnu debian 7.7 version, built from source.
I remember it used to work in prior versions, but today I'm not able to run concurrent Jobs to both disks. The second backup job stalls with the following status: "BackupArquivosBacula is waiting on Storage Disco".
Bellow are the configurations and the bacula-sd -d 100 output of both job executions:

# bacula-sd.conf

Autochanger {
Name = FileChgr1
Device = Disco1, Disco2
Changer Command = "/dev/null"
Changer Device = /dev/null
}

Device {
Name = "Disco1"
Drive Index = 0
Media Type = File1
Archive Device = /backup1
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

Device {
Name = "Disco2"
Drive Index = 1
Media Type = File2
Archive Device = /backup2
LabelMedia = yes; # lets Bacula label unlabeled media
Random Access = Yes;
AutomaticMount = yes; # when device opened, read it
RemovableMedia = no;
AlwaysOpen = no;
Maximum Concurrent Jobs = 1
Volume Poll Interval = 15
}

# bacula-dir.conf

Storage {
Name = "Disco"
Address = 192.168.12.6
SDPort = 9103
Password = ""
Device = FileChgr1
Media Type = File1
Maximum Concurrent Jobs = 10
Autochanger = yes
}


# bacula-sd -d 100

SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=10 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=10 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2991 === nwriters=1 nres=1 vcatjob=11 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=13305449986 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 EndTime=1484664931 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=169695 VolBytes=13305449986 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=169696 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=30427377 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2991 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2991 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2991 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: bnet.c:566-0 who=client host=192.168.12.6 port=9103
SRV-BACKUP-sd: dircmd.c:178-0 Got a DIR connection at 17-Jan-2017 12:55:35
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <255618738.1484664935@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <1751508803.1484664935@SRV-BACKUP-dir> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: /Q/kimxPmVwym5/GxW+kzD
SRV-BACKUP-sd: dircmd.c:204-0 Message channel init completed.
SRV-BACKUP-sd: job.c:78-0 <dird: JobId=2992 job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 job_name=Backup-Aroeira-fd client_name=AROEIRA-fd type=66 level=70 FileSet=servidores_linux_set NoAttr=0 SpoolAttr=1 FileSetMD5=BD+1l4wAW4+zoXp991gLrC SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy
SRV-BACKUP-sd: job.c:100-0 rerunning=0 VolSesId=0 VolSesTime=0
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1 LI=1493 StartBlock=420548098 EndBlock=1420484028 Wrote
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14305385917 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 EndTime=1484664991 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=185195 VolBytes=14305385917 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=185196 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31441556 EndFile=2 EndBlock=3260124974 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: block_util.c:673-2991 dir_update_volume_info max file size -- OK
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: label.c:800-2991 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2991 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2991 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2991 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: askdir.c:570-2991 JobMedia Vol=Diaria-Disco-4 wrote=1 MediaId=113 FI=1493 LI=3245 StartBlock=1420484029 EndBlock=1605729006 Wrote
SRV-BACKUP-sd: acquire.c:490-2991 release_device device "Disco1" (/backup1) is disk
SRV-BACKUP-sd: acquire.c:514-2991 There are 0 writers in release_device
SRV-BACKUP-sd: askdir.c:382-2991 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2991 >dird CatReq Job=BackupArquivosBacula.2017-01-17_12.55.29_27 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2991 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=11 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:550-2991 0 writers, 0 reserve, dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:732-2991 close_dev vol=Diaria-Disco-4 fd=8 dev=110c4c8 dev="Disco1" (/backup1)
SRV-BACKUP-sd: dev.c:826-2991 Enter unmount
SRV-BACKUP-sd: dev.c:525-2991 Clear volhdr vol=Diaria-Disco-4
SRV-BACKUP-sd: acquire.c:591-2991 JobId=2991 broadcast wait_device_release at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:611-2991 Device "Disco1" (/backup1) released by JobId=2991
SRV-BACKUP-sd: spool.c:674-2991 Commit attributes at 17-Jan-2017 12:56:41
SRV-BACKUP-sd: acquire.c:652-2992 Detach 0x11276a8 from olddev "Disco1" (/backup1)
SRV-BACKUP-sd: acquire.c:673-2992 Attach 0x11276a8 to dev "Disco1" (/backup1)
SRV-BACKUP-sd: job.c:188-2992 sd_calls_client=0 sd_client=0
SRV-BACKUP-sd: job.c:210-2992 Backup-Aroeira-fd.2017-01-17_12.55.32_28 waiting 1800 sec for FD to contact SD key=PGBJ-MOCK-BPHN-AGIG-NAIK-EPJF-IPCF-IFIO
SRV-BACKUP-sd: bnet.c:566-0 who=client host=200.137.236.2 port=9103
SRV-BACKUP-sd: hello.c:134-0 Conn: Hello Start Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:154-0 Found Client Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:165-0 fd_version=0 sd_version=0
SRV-BACKUP-sd: authenticate.c:157-0 Challenge FD
SRV-BACKUP-sd: cram-md5.c:69-0 send: auth cram-md5 challenge <1130532902.1484665001@SRV-BACKUP-sd> ssl=0
SRV-BACKUP-sd: authenticate.c:161-0 Respond to FD challenge
SRV-BACKUP-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 <16476019.1484664933@AROEIRA-fd> ssl=0
SRV-BACKUP-sd: cram-md5.c:157-0 sending resp to challenge: J6+zB6+We/sm23+fWnwqqD
SRV-BACKUP-sd: hello.c:182-0 OK Authentication jid=2992 Job Backup-Aroeira-fd.2017-01-17_12.55.32_28
SRV-BACKUP-sd: hello.c:199-0 === Auth OK, unblock Job Backup-Aroeira-fd.2017-01-17_12.55.32_28 jid=2992 sd_ver=0
SRV-BACKUP-sd: job.c:228-2992 === Auth=2992 jid=1 canceled=0 errstat=0
SRV-BACKUP-sd: fd_cmds.c:125-2992 ==== JobType=B run_job=2992 sd_client=0
SRV-BACKUP-sd: fd_cmds.c:149-2992 Do: do_client_commands
SRV-BACKUP-sd: append.c:86-2992 Start append data. res=1
SRV-BACKUP-sd: acquire.c:384-2992 acquire_append device is disk
SRV-BACKUP-sd: mount.c:71-2992 Enter mount_next_volume(release=0) dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:83-2992 mount_next_vol retry=0
SRV-BACKUP-sd: mount.c:638-2992 No swap_dev set. dev->vol=1126f08
SRV-BACKUP-sd: mount.c:118-2992 After find_a_volume. Vol=Diaria-Disco-4 Slot=0 VolType=1
SRV-BACKUP-sd: autochanger.c:106-2992 Device "Disco1" (/backup1) is not an autochanger
SRV-BACKUP-sd: mount.c:146-2992 autoload_dev returns 0
SRV-BACKUP-sd: mount.c:162-2992 Ask=0 autochanger=0
SRV-BACKUP-sd: mount.c:177-2992 want vol=Diaria-Disco-4 devvol= dev="Disco1" (/backup1)
SRV-BACKUP-sd: mount.c:191-2992 Try open Vol=Diaria-Disco-4
SRV-BACKUP-sd: dev.c:387-2992 call open_file_device mode=OPEN_READ_WRITE
SRV-BACKUP-sd: file_dev.c:179-2992 open disk: mode=OPEN_READ_WRITE open(/backup1/Diaria-Disco-4, 0x2, 0640)
SRV-BACKUP-sd: file_dev.c:188-2992 Did open(/backup1/Diaria-Disco-4,OPEN_READ_WRITE,0640)
SRV-BACKUP-sd: file_dev.c:204-2992 open dev: disk fd=11 opened
SRV-BACKUP-sd: dev.c:391-2992 preserve=0x0 fd=11
SRV-BACKUP-sd: dev.c:394-2992 open dev: fd=11 dev=110c8a6 dcr=110c4c8 vol= type=1 dev_name="Disco1" (/backup1) mode=OPEN_READ_WRITE
SRV-BACKUP-sd: label.c:75-2992 Enter read_volume_label res=1 device="Disco1" (/backup1) vol=Diaria-Disco-4 dev_Vol=*NULL*
SRV-BACKUP-sd: label.c:879-2992 Enter: bool unser_volume_label(DEVICE*, DEV_RECORD*)
SRV-BACKUP-sd: label.c:896-2992 reclen=180 recdata=Bacula 1.0 immortal

SRV-BACKUP-sd: label.c:898-2992 reclen=180 recdata=Bacula 1.0 immortal


Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:929-2992 Leave: bool unser_volume_label(DEVICE*, DEV_RECORD*)

Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : Diaria-Disco-4
PrevVolName :
VolFile : 0
LabelType : VOL_LABEL
LabelSize : 180
PoolName : Diaria-Disco
MediaType : File1
PoolType : Backup
HostName : SRV-BACKUP
Date label written: 17-Jan-2017 11:57
SRV-BACKUP-sd: label.c:232-2992 Call reserve_volume=Diaria-Disco-4
SRV-BACKUP-sd: mount.c:261-2992 Do not have volcatinfo
SRV-BACKUP-sd: mount.c:295-2992 Device previously written, moving to end of data. Expect 14490630895 bytes
SRV-BACKUP-sd: tape_dev.c:264-2992 Enter: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: tape_dev.c:294-2992 Leave: bool DEVICE::eod(DCR*)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665001 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=11 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: acquire.c:452-2992 === nwriters=1 nres=1 vcatjob=12 dev="Disco1" (/backup1)
SRV-BACKUP-sd: askdir.c:382-2992 Update cat VolBytes=14490630895 Status=Append Vol=Diaria-Disco-4
SRV-BACKUP-sd: askdir.c:413-2992 >dird CatReq Job=Backup-Aroeira-fd.2017-01-17_12.55.32_28 UpdateMedia VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 EndTime=1484665002 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 VolFirstWritten=0 VolType=1
SRV-BACKUP-sd: askdir.c:428-2992 get_volume_info() 1000 OK VolName=Diaria-Disco-4 VolJobs=12 VolFiles=3 VolBlocks=188067 VolBytes=14490630895 VolABytes=0 VolHoleBytes=0 VolHoles=0 VolMounts=12 VolErrors=0 VolWrites=188068 MaxVolBytes=21474836480 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=31544358 EndFile=3 EndBlock=1605729006 VolType=1 LabelType=0 MediaId=113 ScratchPoolId=0
SRV-BACKUP-sd: append.c:108-2992 Begin append device="Disco1" (/backup1)
SRV-BACKUP-sd: append.c:113-2992 Just after acquire_device_for_append
SRV-BACKUP-sd: label.c:800-2992 Enter: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: label.c:741-2992 Enter: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:785-2992 Leave: void create_session_label(DCR*, DEV_RECORD*, int)
SRV-BACKUP-sd: label.c:859-2992 Leave: bool write_session_label(DCR*, int)
SRV-BACKUP-sd: device.c:223-2992 set_new_volume_parameters dev="Disco1" (/backup1)
SRV-BACKUP-sd: spool.c:761-2991 Close attr spool file at 17-Jan-2017 12:56:50
SRV-BACKUP-sd: append.c:358-2991 return from do_append_data() ok=1
SRV-BACKUP-sd: fd_cmds.c:161-2991 ==== 3099 Job BackupArquivosBacula.2017-01-17_12.55.29_27 end JobStatus=84 JobFiles=3245 JobBytes=1183712749 JobErrors=0
SRV-BACKUP-sd: jcr.c:174-2991 write_last_jobs seek to 192

Regards,
--
===========================================================================
Heitor Medrado de Faria  | Bacula do Brasil
• Não seja tarifado pelo tamanho dos seus backups, conheça o Bacula Enterprise: http://www.bacula.com.br/enterprise/
 Ministro treinamento e implementação in-company do Bacula Community: http://www.bacula.com.br/in-company/
(61) 98268-4220 | www.bacula.com.br 
============================================================================
Indicamos também as capacitações complementares:
 Shell básico e Programação em Shell com Julio Neves.
 Zabbix com Adail Host.
============================================================================


------------------------------------------------------------------------------
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
[hidden email]https://lists.sourceforge.net/lists/listinfo/bacula-users




--
===========================================================================
Heitor Medrado de Faria  | Bacula do Brasil
• Não seja tarifado pelo tamanho dos seus backups, conheça o Bacula Enterprise: http://www.bacula.com.br/enterprise/
 Ministro treinamento e implementação in-company do Bacula Community: http://www.bacula.com.br/in-company/
(61) 98268-4220 | www.bacula.com.br 
============================================================================
Indicamos também as capacitações complementares:
 Shell básico e Programação em Shell com Julio Neves.
 Zabbix com Adail Host.
============================================================================

------------------------------------------------------------------------------
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
[hidden email]
https://lists.sourceforge.net/lists/listinfo/bacula-users
Reply | Threaded
Open this post in threaded view
|

Re: Is Virtual Autochanger for Multiple Disks writing still working?

Josh Fisher

On 1/17/2017 12:14 PM, Heitor Faria wrote:
Hello Heitor,
Hello, Kern! Thanks for the quick reply.
Bacula is quite literal (i.e. any spurious characters can cause problems).
Please try replacing your:

Changer Command = "/dev/null"

with either:

Changer Command = /dev/null

or

Changer Command = ""
I tried both ways. Now, with ChangerCommand = "", it seems to look for the other disk. But it always attach the first one:

The Storage resource in bacula-dir.conf related to the FileChgr1 autochanger resource specifies MediaType="File1", therefore it will never attach to Device resource Disco2, because Disco2 has MediaType="File2".

I don't believe it is possible to specify multiple media types for a single bacula-dir Storage resource. You could define two Storage resources, one for each MediaType and divide your jobs among those two. Or you could define two autochangers, one for each mountpoint, so that all of a particular autochanger's Device resources use the same mountpoint as the ArchiveDevice. Alternatively, you can look into vChanger (https://sourceforge.net/projects/vchanger/), which allows using multiple mountpoints as a single "library" of volumes having the same MediaType, and so a single Storage resource.



------------------------------------------------------------------------------
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
[hidden email]
https://lists.sourceforge.net/lists/listinfo/bacula-users