Dificuldade em configurar o Bacula utilizando Dell PowerVault TL1000 Tape Library

398 views
Skip to first unread message

Rodrigo Braga Prado

unread,
Oct 16, 2017, 3:40:58 PM10/16/17
to Bacula Backup-Pt-Br
Boa pessoas, 

            Estou configurando um servidor bacula para utilizar uma biblioteca de fitas para os servidores de backups atuais.

            Falando um pouco do cenário atual.

            Atualmente possuo 6 servidores de backup que estão funcionais e estou montando o sétimo servidor de backup, esse servidor será a biblioteca de fitas que irá salvar os backups em fitas, e nesse servidor (HP ProLiant ML350 G6  Xeon E5...@2.40GHz) esta ligado um Dell PowerVault TL1000 Tape Library de 9 Fitas.
             Esse servidor esta utilizando o bacula 7.0.5 no ubuntu 16.04.3, instalado via repositório.

            Fiz alguns testes usando o tar direto e funcionou, então a biblioteca esta funcionando, apartir disso comecei a configurar o bacula.

            O servidor que tem as fitas esta com o os demais servidores montados via nfs como abaixo:

NFS
backups1.fakedomain.br:/backups   17T   14T  2,6T  85% /backups1
backups2.fakedomain.br:/backups   11T  4,9T  6,1T  45% /backups2
backups3.fakedomain.br:/backups   19T   12T  6,5T  65% /backups3
backups4.fakedomain.br:/backups   15T   15T  352G  98% /backups4
backups5.fakedomain.br:/backups   22T   11T   11T  50% /backups5
backups6.fakedomain.br:/backups   22T  9,1T   13T  42% /backups6

            A unidade de fita esta acessível como pode ser visto abaixo:

lsscsi -g
[1:0:0:0]    cd/dvd  hp       DVDROM DH40N     IS01  /dev/sr0   /dev/sg0
[2:0:0:0]    storage HP       P410i            3.66  -          /dev/sg1
[2:0:1:0]    tape    IBM      ULT3580-HH5      G361  /dev/st0   /dev/sg2
[2:1:0:0]    disk    HP       LOGICAL VOLUME   3.66  /dev/sda   /dev/sg3

           Estou usando esses comandos para debug ter acesso ao debug:

bacula-dir -f -v -d 400 -dt -c /etc/bacula/bacula-dir.conf
bacula-sd -f -v -d 400 -dt -c /etc/bacula/bacula-sd.conf
bacula-fd -f -v -d 400 -dt -c /etc/bacula/bacula-fd.conf

            Meu arquivos de configuração:

bacula-dir.conf
#################################
# Configuração básica do Bacula #
#################################

###########
# Diretor #
###########
Director {
                Name = "director-backups7"
                DIRport = 9101
                QueryFile = "/etc/bacula/scripts/query.sql"
                WorkingDirectory = "/var/lib/bacula"
                PidDirectory = "/var/run/bacula"
                Maximum Concurrent Jobs = "20"
                Password = "senha"
                Messages = "Daemon"
                DirAddress = backups7.fakedomain.br
        }

##################################
# Store - Local de armazenamento #
##################################
Storage {
                Name = "Storage-Biblioteca-de-Fitas"
                Address = "backups7.fakedomain.br"
                SDAddress = "backups7.fakedomain.br"
                SDPort = "9103"
                Password = "senha"
                Device = "/backups"
                Media Type = "LTO-4"
                Maximum Concurrent Jobs = "1"
                AllowCompression = Yes
        }

Storage {
                Name = "Storage-Backup-SSH-Fita"
                Address = "backups7.fakedomain.br"
                SDAddress = "backups7.fakedomain.br"
                SDPort = "9103"
                Password = "senha"
                Device = "Device-Tape-Drive"
                Media Type = "LTO-4"
                Maximum Concurrent Jobs = "1"
                AllowCompression = Yes
        }

Storage {
                Name = "Storage-Backups"
                Address = "backups7.fakedomain.br"
                SDAddress = "backups7.fakedomain.br"
                SDPort = "9103"
                Password = "senha"
                Device = "Device-Tape-Drive"
                Media Type = "LTO-4"
                Maximum Concurrent Jobs = "1"
                AllowCompression = Yes
        }


#########################
# Definição dos backups #
#########################
JobDefs {
                Name = "JobDefs-Backup-do-SSH"
                Type = "Backup"
                Level = "Full"
                Client = backups7-fd
                FileSet = "FileSet-Gateway-SSH"
                Schedule = "Agendamentos-fakedomain"
                Storage = "Storage-Backup-SSH-Fita"
                Messages = "Standard"
                Pool = "Pool-Backup-fakedomain"
                SpoolAttributes = "yes"
                Priority = "10"
        }

JobDefs {
                Name = "JobDefs-Backup-do-SSH-Fita"
                Type = "Backup"
                Level = "Full"
                Client = backups7-fd
                FileSet = "FileSet-Gateway-SSH"
                Schedule = "Agendamentos-fakedomain"
                Storage = "Storage-Backup-SSH-Fita"
                Messages = "Standard"
                Pool = "Pool-Backup-fakedomain"
                SpoolAttributes = "yes"
                Priority = "10"
        }

JobDefs {
                Name = "JobDefs-Backups"
                Type = "Backup"
                Level = "Full"
                Client = backups7-fd
                FileSet = "FileSet-Backups1"
                Schedule = "Agendamentos-fakedomain"
                Storage = "Storage-Backups"
                Messages = "Standard"
                Pool = "Pool-Backup-fakedomain"
                SpoolAttributes = "yes"
                Priority = "10"
        }

###########
# Backups #
###########
        Job {
                Name = "Job-Backup-Gateway-SSH-Fita"
                JobDefs = "JobDefs-Backup-do-SSH-Fita"
                Type = "Backup"
                Pool = "Pool-Backup-Diario"
                Level = Full
                FileSet="FileSet-Gateway-SSH"
                Schedule = "Agendamentos-fakedomain"
                Priority = "11"
                Where = "/dev/st0"
                                RunBeforeJob = "/etc/bacula/scripts/make_catalog_backup.pl MyCatalog"
                RunAfterJob  = "/etc/bacula/scripts/delete_catalog_backup"
         }

        Job {
                Name = "Job-Backup-Backups1"
                JobDefs = "JobDefs-Backups"
                Type = "Backup"
                Pool = "Pool-Backup-Diario"
                Level = Full
                FileSet="FileSet-Backups1"
                Schedule = "Agendamentos-fakedomain"
                Priority = "11"
                Where = "/dev/st0"
                RunBeforeJob = "/etc/bacula/scripts/make_catalog_backup.pl MyCatalog"
                RunAfterJob  = "/etc/bacula/scripts/delete_catalog_backup"
        }



###################
# Jobs - Restores #
###################
        Job {
                Name = "Job-Restore-Backup-Gateway-SSH-Fita"
                JobDefs = "JobDefs-Backup-do-SSH-Fita"
                Type = "Restore"
                Pool = "Pool-Backup-Diario"
                Level = Full
                FileSet="FileSet-Gateway-SSH"
                Schedule = "Agendamentos-fakedomain"
                Priority = "11"
                Where = "/backups/restore"
                                RunBeforeJob = "/etc/bacula/scripts/make_catalog_backup.pl MyCatalog"
                RunAfterJob  = "/etc/bacula/scripts/delete_catalog_backup"
        }

        Job {
                Name = "Job-Restore-Backup-Backups1"
                JobDefs = "JobDefs-Backups"
                Type = "Restore"
                Pool = "Pool-Backup-Diario"
                Level = Full
                FileSet="FileSet-Backups1"
                Schedule = "Agendamentos-fakedomain"
                Priority = "11"
                Where = "/backups/restore"
                                RunBeforeJob = "/etc/bacula/scripts/make_catalog_backup.pl MyCatalog"
                RunAfterJob  = "/etc/bacula/scripts/delete_catalog_backup"
        }


######################################
# Lista de arquivos a serem copiados #
######################################
FileSet {
                Name = "Backup completo"
                Include {
                        Options {
                                        signature = MD5
                                        compression = GZIP
                                }
                                File = /backups1
#                               File = /backups2
#                               File = /backups3
#                               File = /backups4
#                               File = /backups5
#                               File = /backups6
                        }

#                       Exclude {
#                                       File = /var/lib/bacula
#                                       File = /proc
#                                       File = /tmp
#                                       File = /sys
#                                       File = /.journal
#                                       File = /.fsck
#                               }
        }

FileSet {
        Name = "Catalog"
        Include {
                        Options {
                                        signature = MD5
                                        compression = GZIP
                                }
                                        File = "/var/lib/bacula/bacula.sql"
                }
}

FileSet {
        Name = "FileSet-Gateway-SSH"
        Include {
                        Options {
                                        signature = "MD5"
                                        compression = "GZIP"
                        }
                        File = "/backups1/gateway-ssh"
                }
        }

FileSet {
        Name = "FileSet-Backups1"
        Include {
                        Options {
                                        signature = "MD5"
                                        compression = "GZIP"
                        }
                        File = "/backups1/"
                }
        }

################
# Agendamentos #
################
Schedule {
                Name = "Agendamentos"
                Run = Full 1st sun at 23:05
                Run = Differential 2nd-5th sun at 23:05
                Run = Incremental mon-sat at 23:05
        }

Schedule {
                Name = "Agendamentos-fakedomain"
                Run = Incremental       Pool=Pool-Backup-Diario                 monday-thursday         at 22:00
                Run = Differential      Pool=Pool-Backup-Diferencial            monday-friday           at 02:00
                Run = Full              Pool=Pool-Backup-Semanal                2nd-5th friday          at 22:00
                Run = Full              Pool=Pool-Backup-Mensal                 1st friday              at 22:00
        }

############
# Clientes #
############
Client {
                Name = "backups7-fd"
                Address = "backups7.fakedomain.br"
                FDPort = "9102"
                Catalog = "MyCatalog"
                Password = "senha"
                File Retention = 60 days
                Job Retention = 6 months
                AutoPrune = "yes"
        }

Client {
                Name = "backups-em-fita"
                Address = "backups7.fakedomain.br"
                FDPort = "9102"
                Catalog = "MyCatalog"
                Password = "senha"
                File Retention = 30 days
                Job Retention = 6 months
                AutoPrune = "yes"
        }

##################################
# Configuração do Banco de dados #
##################################
Catalog {
                Name = "MyCatalog"
                dbname = "bacula";
                DB Address = "localhost";
                dbuser = "bacula";
                dbpassword = "senha";
        }

#######################
# Mensagens do bacula #
#######################
Messages {
                Name = Standard
                mailcommand = "/usr/sbin/bsmtp -h localhost -f \"\(Bacula\) \<%r\>\" -s \"Bacula: %t %e of %c %l\" %r"
                operatorcommand = "/usr/sbin/bsmtp -h localhost -f \"\(Bacula\) \<%r\>\" -s \"Bacula: Intervention needed for %j\" %r"
                mail = root = all, !skipped
                operator = root = mount
                console = all, !skipped, !saved
                append = "/var/log/bacula/bacula.log" = all, !skipped
                catalog = all
        }

Messages {
                Name = Daemon
                mailcommand = "/usr/sbin/bsmtp -h localhost -f \"\(Bacula\) \<%r\>\" -s \"Bacula daemon message\" %r"
                mail = root = all, !skipped
                console = all, !skipped, !saved
                append = "/var/log/bacula/bacula.log" = all, !skipped
        }

#########
# Pools #
#########

##############
# Pool types #
###################
#       Backup    #
#       Archive   #
#       Cloned    #
#       Migration #
#       Copy      #
#       Save      #
###################

        Pool {
                Name = Pool-Backup-fakedomain
                Pool Type = Backup
                Recycle = yes
                AutoPrune = yes
                Volume Retention = 365 days
                Maximum Volume Bytes = 1500G
                Maximum Volumes = 9
                Label Format = "Backups-"
        }

        Pool {
                Name = Pool-Backup-Diario
                Pool Type = Backup
                Recycle = yes
                AutoPrune = yes
                Volume Retention = 1 days
                Maximum Volume Bytes = 1500G
                Maximum Volumes = 9
                Label Format = "Backups-"
        }

        Pool {
                Name = Pool-Backup-Semanal
                Pool Type = Backup
                Recycle = yes
                AutoPrune = yes
                Volume Retention = 7 days
                Maximum Volume Bytes = 1500G
                Maximum Volumes = 9
                Label Format = "Backups-"
        }

        Pool {
                Name = Pool-Backup-Mensal
                Pool Type = Backup
                Recycle = yes
                AutoPrune = yes
                Volume Retention = 365 days
                Maximum Volume Bytes = 1500G
                Maximum Volumes = 9
                Label Format = "Backups-"
        }

        Pool {
                Name = Pool-Backup-Diferencial
                Pool Type = Backup
                Recycle = yes
                AutoPrune = yes
                Volume Retention = 365 days
                Maximum Volume Bytes = 1500G
                Maximum Volumes = 9
                Label Format = "Backups-"
        }


#####################
# Console do bacula #
#####################
Console {
                Name = console-backups7-mon
                Password = "senha"
                CommandACL = status, .status
        }


bacula-sd.conf
###########################
# Configuração do serviço #
###########################
Storage {
                Name = storage-backups7-sd
                SDPort = 9103
                WorkingDirectory = "/var/lib/bacula"
                Pid Directory = "/var/run/bacula"
                Maximum Concurrent Jobs = 20
                SDAddress = backups7.fakedomain.br
                Heartbeat Interval = 1 Minute
                Maximum Concurrent Jobs = 10
        }

#################
# Monitoramento #
#################
Director {
                Name = director-backups7
                Password = "senha"
}

Director {
                Name = director-backups7-mon
                Password = "senha"
                Monitor = yes
}

#######################
# Biblioteca de Fitas #
#######################
Autochanger {
                Name = Autochanger
                Device = Device-Tape-Drive, Device-Disk-Local
                Changer Command = "/usr/sbin/mtx %c %o %S %a %d"
                Changer Device = /dev/sg2
        }

Device {

# [1:0:0:0]    cd/dvd  hp       DVDROM DH40N     IS01  /dev/sr0   /dev/sg0
# [2:0:0:0]    storage HP       P410i            3.66  -          /dev/sg1
# [2:0:1:0]    tape    IBM      ULT3580-HH5      G361  /dev/st0   /dev/sg2 <===
# [2:1:0:0]    disk    HP       LOGICAL VOLUME   3.66  /dev/sda   /dev/sg3

                Name = Device-Tape-Drive
                Device Type = Tape
                Media Type = LTO-4
                                Archive Device = /dev/nst0
                Hardware end of medium = No;
                AutomaticMount = yes;
                AlwaysOpen = Yes;
                RemovableMedia = yes;
                RandomAccess = no;
                Alert Command = "sh -c 'tapeinfo -f %c | grep TapeAlert'"

                                Changer Device = /dev/sg2
                LabelMedia = yes;
                Autochanger = yes;
                                Spool Directory = /backups
                Maximum Spool Size = 44424509440 # checar o tamanho
                Maximum Changer Wait = 100000    # checar o tamanho
        }

Device {
                Name = Device-Disk-Local
                Media Type = File
                Archive Device = /backups
                LabelMedia = yes
                Random Access = Yes
                AutomaticMount = yes
                RemovableMedia = no
                AlwaysOpen = yes
        }

########################
# Mensagens do sistema #
########################
Messages {
                Name = Standard
                director = backups7-dir = all
                mail = root = all, !skipped
                console = all, !skipped, !saved
                append = "/var/log/bacula/bacula.log" = all, !skipped
                mailcommand = "/usr/sbin/bsmtp -h localhost -f \"\(Bacula\) \<%r\>\" -s \"Bacula daemon message\" %r"
                operatorcommand = "/usr/sbin/bsmtp -h localhost -f \"\(Bacula\) \<%r\>\" -s \"Bacula: Intervention needed for %j\" %r"
        }



bacula-fd.conf
###########################
# Configuração do serviço #
###########################
FileDaemon {
                Name = filedaemon-backups7-fd
                FDport = 9102
                WorkingDirectory = /var/lib/bacula
                Pid Directory = /var/run/bacula
                Maximum Concurrent Jobs = 20
                Plugin Directory = /usr/lib/bacula
                FDAddress = backups7.fakedomain.br
        }

###########
# Monitor #
###########
Director {
                Name = director-backups7
                Password = "senha"
        }

Director {
                Name = director-backups7-mon
                Password = "senha"
                Monitor = yes
        }

#############
# Mensagens #
#############
Messages {
                Name = Standard
                director = director-backups7-dir = all, !skipped, !restored
        }


      O arquivo do bacula-sd da um erro na saída e creio que seja essa o problema que este não consigo resolver e por consequência não consigo gravar as fitas.

16-Out-2017 17:13:01 storage-backups7-sd: autochanger.c:332-0 Unlocking changer Autochanger
16-Out-2017 17:13:01 storage-backups7-sd: tape_dev.c:96-0 Try open "Device-Tape-Drive" (/dev/nst0) mode=OPEN_READ_ONLY
16-Out-2017 17:13:01 storage-backups7-sd: tape_dev.c:118-0 Rewind after open
16-Out-2017 17:13:14 storage-backups7-sd: os.c:55-0 In set_os_device_parameters
16-Out-2017 17:13:14 storage-backups7-sd: os.c:61-0 Set block size to zero
16-Out-2017 17:13:14 storage-backups7-sd: os.c:77-0 MTSETDRVBUFFER
16-Out-2017 17:13:14 storage-backups7-sd: tape_dev.c:170-0 open dev: tape 7 opened
16-Out-2017 17:13:14 storage-backups7-sd: dev.c:403-0 preserve=0x0 fd=7
16-Out-2017 17:13:14 storage-backups7-sd: dev.c:406-0 open dev: fd=7 dev=7f281000bb86 dcr=7f281000b7f8 vol= type=2 dev_name="Device-Tape-Drive" (/dev/nst0) mode=OPEN_READ_ONLY
16-Out-2017 17:13:14 storage-backups7-sd: device.c:307-0 open dev "Device-Tape-Drive" (/dev/nst0) OK
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:171-0 rUnlock from device.c:310
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:165-0 Unlock from device.c:310 postcnt=0
16-Out-2017 17:13:14 storage-backups7-sd: label.c:72-0 Enter read_volume_label res=0 device="Device-Tape-Drive" (/dev/nst0) vol= dev_Vol=*NULL*
16-Out-2017 17:13:14 storage-backups7-sd: tape_dev.c:185-0 rewind res=0 fd=7 "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:202-0 empty len=64512 block=7f281000c810 set binbuf=24
16-Out-2017 17:13:14 storage-backups7-sd: label.c:123-0 Big if statement in read_volume_label
16-Out-2017 17:13:14 storage-backups7-sd: block.c:377-0 Full read in read_block_from_device() len=64512
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:82-0 !!!Dump block before read 0x1000c810 blocksize too big 1431655765
16-Out-2017 17:13:14 storage-backups7-sd: block.c:418-0 Read() vol= nbytes=10240 addr=-1
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:309-0 block_util.c:308 Volume data error at 0:0! Wanted ID: "BB02", got "". Buffer discarded.
16-Out-2017 17:13:14 storage-backups7-sd: label.c:128-0 Requested Volume "" on tape device "Device-Tape-Drive" (/dev/nst0) is not a Bacula labeled Volume, because: ERR=block_util.c:308 Volume data error at 0:0! Wanted ID: "BB02", got "". Buffer discarded.
16-Out-2017 17:13:14 storage-backups7-sd: label.c:157-0 No volume label - bailing out
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:202-0 empty len=64512 block=7f281000c810 set binbuf=24
16-Out-2017 17:13:14 storage-backups7-sd: tape_dev.c:185-0 rewind res=0 fd=7 "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:13:14 storage-backups7-sd: label.c:248-0 return 3
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:156-0 Lock from acquire.c:733 precnt=0
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:165-0 Unlock from acquire.c:747 postcnt=0
16-Out-2017 17:13:14 storage-backups7-sd: stored.c:556-0 calling init_dev /backups
16-Out-2017 17:13:14 storage-backups7-sd: sd_plugins.c:155-0 No bplugin_list: generate_global_plugin_event ignored.
16-Out-2017 17:13:14 storage-backups7-sd: dev.c:199-0 Allocate dev="Device-Disk-Local" (/backups)
16-Out-2017 17:13:14 storage-backups7-sd: dev.c:354-0 init_dev: tape=0 dev_name=/backups
16-Out-2017 17:13:14 storage-backups7-sd: stored.c:558-0 SD init done /backups
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:202-0 empty len=64512 block=7f2810036f90 set binbuf=24
16-Out-2017 17:13:14 storage-backups7-sd: block_util.c:138-0 New block len=64512 block=7f2810036f90
16-Out-2017 17:13:14 storage-backups7-sd: acquire.c:668-0 Attach 0x10037fd8 to dev "Device-Disk-Local" (/backups)
16-Out-2017 17:13:14 storage-backups7-sd: sd_plugins.c:101-0 No bplugin_list: generate_plugin_event ignored.
16-Out-2017 17:13:14 storage-backups7-sd: stored.c:572-0 calling first_open_device "Device-Disk-Local" (/backups)
16-Out-2017 17:13:14 storage-backups7-sd: device.c:282-0 start open_output_device()
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:211-0 rLock blked=BST_NOT_BLOCKED from device.c:287
16-Out-2017 17:13:14 storage-backups7-sd: device.c:291-0 Device is file, deferring open.
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:171-0 rUnlock from device.c:310
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:165-0 Unlock from device.c:310 postcnt=0
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:156-0 Lock from acquire.c:733 precnt=0
16-Out-2017 17:13:14 storage-backups7-sd: lock.c:165-0 Unlock from acquire.c:747 postcnt=0


          Já alterei para /dev/st0, /dev/sg2 e ainda não consigo gravar o raio da fita.
          Tentando rodar um job eu tenho as seguintes saídas:

Saída do bacula-dir
16-Out-2017 17:20:30 director-backups7: sql_find.c:377-73 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,VolParts,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge FROM Media WHERE PoolId=10 AND MediaType='LTO-4' AND Enabled=1 AND VolStatus='Purged'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
16-Out-2017 17:20:30 director-backups7: sql_find.c:385-73 item=1 got=0
16-Out-2017 17:20:30 director-backups7: recycle.c:62-73 return 0  recycle_oldest_purged_volume end
16-Out-2017 17:20:30 director-backups7: next_vol.c:116-73 after prune volumes_vol ok=0 index=1 InChanger=0 Vstat=Purged
16-Out-2017 17:20:30 director-backups7: sql_find.c:377-73 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,VolParts,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge FROM Media WHERE PoolId=3 AND MediaType='LTO-4' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
16-Out-2017 17:20:30 director-backups7: sql_find.c:385-73 item=1 got=0
16-Out-2017 17:20:30 director-backups7: sql_find.c:377-73 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,VolParts,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge FROM Media WHERE PoolId=3 AND MediaType='LTO-4' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
16-Out-2017 17:20:30 director-backups7: sql_find.c:385-73 item=1 got=0
16-Out-2017 17:20:30 director-backups7: sql_find.c:377-73 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,VolParts,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge FROM Media WHERE PoolId=3 AND MediaType='LTO-4' AND Enabled=1 AND VolStatus='Purged'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
16-Out-2017 17:20:30 director-backups7: sql_find.c:385-73 item=1 got=0
16-Out-2017 17:20:30 director-backups7: recycle.c:62-73 return 0  recycle_oldest_purged_volume end
16-Out-2017 17:20:30 director-backups7: next_vol.c:123-73 after get scratch volume ok=0 index=1 InChanger=0 Vstat=Purged
16-Out-2017 17:20:30 director-backups7: sql_get.c:675-73 Actual NumVols=9 Pool NumVols=9
16-Out-2017 17:20:30 director-backups7: next_vol.c:180-73 VolJobs=0 FirstWritten=0
16-Out-2017 17:20:30 director-backups7: next_vol.c:195-73 return ok=0 find_next_vol
16-Out-2017 17:20:30 director-backups7: catreq.c:140-73 find_media ok=0 idx=1 vol=
16-Out-2017 17:20:30 director-backups7: catreq.c:369-73 >CatReq response: 1901 No Media.
16-Out-2017 17:20:30 director-backups7: catreq.c:370-73 Leave catreq jcr 0xbc016c28
16-Out-2017 17:20:30 director-backups7: getmsg.c:125-73 bget_dirmsg 388: Jmsg Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 type=10 level=1508181630 storage-backups7-sd JobId 73: Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 is waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "Device-Tape-Drive" (/dev/nst0)
    Pool:         Pool-Backup-Diario
    Media type:   LTO-4

16-Out-2017 17:20:30 director-backups7: util.c:725-73 edit_job_codes: /usr/sbin/bsmtp -h localhost -f "(Bacula) <%r>" -s "Bacula: Intervention needed for %j" %r
16-Out-2017 17:20:30 director-backups7: getmsg.c:125-73 bget_dirmsg 76: Status Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 JobStatus=109

Saída do bacula-sd

16-Out-2017 17:20:30 storage-backups7-sd: bnet.c:566-0 who=client host=192.168.10.123 port=9103
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:174-0 Conn: Hello SD: Bacula Director director-backups7 calling 1
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:195-0 Got a DIR connection at 16-Out-2017 17:20:30
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:60-0 send: auth cram-md5 challenge <1119578686.1508181630@storage-backups7-sd> ssl=0
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:119-0 cram-get received: auth cram-md5 <1371181855.1508181630@director-backups7> ssl=0
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:139-0 sending resp to challenge: fX+Sn+/IhTR1pl+jNC/7WC
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:218-0 Message channel init completed.
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:225-0 <dird: JobId=73 job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 job_name=Job-Backup-Gateway-SSH-Fita client_name=backups7-fd type=66 level=70 FileSet=FileSet-Gateway-SSH NoAttr=0 SpoolAttr=1 FileSetMD5=gH9aqzl5N8/9P60ut9+LCD SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy

16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:239-0 Do command: JobId=
16-Out-2017 17:20:30 storage-backups7-sd: job.c:74-0 <dird: JobId=73 job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 job_name=Job-Backup-Gateway-SSH-Fita client_name=backups7-fd type=66 level=70 FileSet=FileSet-Gateway-SSH NoAttr=0 SpoolAttr=1 FileSetMD5=gH9aqzl5N8/9P60ut9+LCD SpoolData=0 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 sd_client=0 Authorization=dummy
16-Out-2017 17:20:30 storage-backups7-sd: job.c:96-0 rerunning=0 VolSesId=0 VolSesTime=0
16-Out-2017 17:20:30 storage-backups7-sd: job.c:149-73 >dird jid=73: 3000 OK Job SDid=1 SDtime=1508181181 Authorization=PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
16-Out-2017 17:20:30 storage-backups7-sd: sd_plugins.c:282-73 === enter new_plugins ===
16-Out-2017 17:20:30 storage-backups7-sd: sd_plugins.c:284-73 No sd plugin list!
16-Out-2017 17:20:30 storage-backups7-sd: sd_plugins.c:101-73 No bplugin_list: generate_plugin_event ignored.
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:225-73 <dird: use storage=Storage-Backup-SSH-Fita media_type=LTO-4 pool_name=Pool-Backup-Diario pool_type=Backup append=1 copy=0 stripe=0

16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:239-73 Do command: use storage=
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:201-73 <dird: use storage=Storage-Backup-SSH-Fita media_type=LTO-4 pool_name=Pool-Backup-Diario pool_type=Backup append=1 copy=0 stripe=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:230-73 <dird device: use device=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:284-73 lock_reservations at reserve.c:284 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:284-73 lock_reservations: got lock
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:408-73 Start find_suit_dev PrefMnt=1 exact=1 suitable=0 chgronly=0 any=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:536-73 search res for Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:539-73 Try match changer res=Autochanger
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:573-73 Try match res=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:631-73 chk MediaType device=LTO-4 request=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:654-73 try reserve Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:202-73 empty len=64512 block=7f281002f7d0 set binbuf=24
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:138-73 New block len=64512 block=7f281002f7d0
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:668-73 Attach 0x1002f308 to dev "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:718-73 Attach Jid=73 dcr=7f281002f308 size=0 dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:671-73 call reserve for append: have_vol=0 vol=
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from reserve.c:798 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:818-73 reserve_append tape device is "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:966-73 PrefMnt=1 exact=1 suitable=1 chgronly=0 any=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:901-73 MaxJobs=0 Jobs=0 reserves=0 Status= Vol=
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:1009-73 Failed: 3606 JobId=73 prefers mounted drives, but tape device "Device-Tape-Drive" (/dev/nst0) has no Volume.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:822-73 can_reserve_drive!=1
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from reserve.c:835 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:767-73 Not OK.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:573-73 Try match res=Device-Disk-Local
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:512-73 No usable device found.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:522-73 Leave find_suit_dev: no dev found.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:408-73 Start find_suit_dev PrefMnt=1 exact=0 suitable=1 chgronly=0 any=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:536-73 search res for Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:539-73 Try match changer res=Autochanger
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:573-73 Try match res=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:631-73 chk MediaType device=LTO-4 request=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:654-73 try reserve Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:648-73 Detach 0x1002f308 from olddev "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from acquire.c:733 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:739-73 Detach Jid=73 dcr=7f281002f308 size=1 to dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from acquire.c:747 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:202-73 empty len=64512 block=7f281002be40 set binbuf=24
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:138-73 New block len=64512 block=7f281002be40
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:668-73 Attach 0x1002f308 to dev "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:718-73 Attach Jid=73 dcr=7f281002f308 size=0 dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:671-73 call reserve for append: have_vol=0 vol=
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from reserve.c:798 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:818-73 reserve_append tape device is "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:966-73 PrefMnt=1 exact=0 suitable=1 chgronly=0 any=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:901-73 MaxJobs=0 Jobs=0 reserves=0 Status= Vol=
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:1009-73 Failed: 3606 JobId=73 prefers mounted drives, but tape device "Device-Tape-Drive" (/dev/nst0) has no Volume.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:822-73 can_reserve_drive!=1
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from reserve.c:835 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:767-73 Not OK.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:573-73 Try match res=Device-Disk-Local
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:512-73 No usable device found.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:522-73 Leave find_suit_dev: no dev found.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:408-73 Start find_suit_dev PrefMnt=1 exact=0 suitable=1 chgronly=0 any=1
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:536-73 search res for Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:539-73 Try match changer res=Autochanger
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:573-73 Try match res=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:631-73 chk MediaType device=LTO-4 request=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:654-73 try reserve Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:648-73 Detach 0x1002f308 from olddev "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from acquire.c:733 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:739-73 Detach Jid=73 dcr=7f281002f308 size=1 to dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from acquire.c:747 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:202-73 empty len=64512 block=7f281002be40 set binbuf=24
16-Out-2017 17:20:30 storage-backups7-sd: block_util.c:138-73 New block len=64512 block=7f281002be40
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:668-73 Attach 0x1002f308 to dev "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:718-73 Attach Jid=73 dcr=7f281002f308 size=0 dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:671-73 call reserve for append: have_vol=0 vol=
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from reserve.c:798 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:818-73 reserve_append tape device is "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:966-73 PrefMnt=1 exact=0 suitable=1 chgronly=0 any=1
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:901-73 MaxJobs=0 Jobs=0 reserves=0 Status= Vol=
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:1070-73 OK Dev avail reserved "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: sd_plugins.c:101-73 No bplugin_list: generate_plugin_event ignored.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:114-73 Inc reserve=0 dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from reserve.c:835 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:680-73 Reserved=1 dev_name=Device-Tape-Drive mediatype=LTO-4 pool=Pool-Backup-Diario ok=1
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:682-73 Vol= num_writers=0, have_vol=0
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:693-73 no vol, call find_next_appendable_vol.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:258-73 dir_find_next_appendable_volume: reserved=1 Vol=
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes at askdir.c:266 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes: got lock
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:276-73 >dird CatReq Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 FindMedia=1 pool_name=Pool-Backup-Diario media_type=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:172-73 <dird 1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:184-73 get_volume_info failed: ERR=1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:310-73 No vol. index 1 return false. dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:318-73 unlock_volumes at askdir.c:318 precnt=1
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:700-73 No next volume found
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:755-73 >dird: 3000 OK use device device=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:583-73 Device Device-Tape-Drive reserved=1 for append.
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:505-73 available device found=Device-Tape-Drive
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:520-73 OK dev found. Vol=
16-Out-2017 17:20:30 storage-backups7-sd: reserve.c:358-73 unlock_reservations at reserve.c:358 precnt=1
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:225-73 <dird: run
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:239-73 Do command: run
16-Out-2017 17:20:30 storage-backups7-sd: job.c:175-73 Run_cmd: run
16-Out-2017 17:20:30 storage-backups7-sd: job.c:185-73 sd_calls_client=0 sd_client=0
16-Out-2017 17:20:30 storage-backups7-sd: job.c:239-73 Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 waiting 1800 sec for FD to contact SD key=PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
16-Out-2017 17:20:30 storage-backups7-sd: bnet.c:566-0 who=client host=192.168.10.123 port=9103
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:174-0 Conn: Hello Bacula SD: Start Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 5
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:185-0 Got a FD connection at 16-Out-2017 17:20:30
16-Out-2017 17:20:30 storage-backups7-sd: dircmd.c:186-0 Hello Bacula SD: Start Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 5
16-Out-2017 17:20:30 storage-backups7-sd: job.c:288-0 Found Filed Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03
16-Out-2017 17:20:30 storage-backups7-sd: job.c:304-0 fd_version=5 sd_version=0
16-Out-2017 17:20:30 storage-backups7-sd: authenticate.c:235-0 Challenge FD
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:60-0 send: auth cram-md5 challenge <2125351066.1508181630@storage-backups7-sd> ssl=0
16-Out-2017 17:20:30 storage-backups7-sd: authenticate.c:239-0 Respond to FD challenge
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:119-0 cram-get received: auth cram-md5 <1936745421.1508181630@filedaemon-backups7-fd> ssl=0
16-Out-2017 17:20:30 storage-backups7-sd: cram-md5.c:139-0 sending resp to challenge: k6+rGCBP45+ev7IMiR/l5B
16-Out-2017 17:20:30 storage-backups7-sd: job.c:314-0 OK Authentication jid=73 Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03
16-Out-2017 17:20:30 storage-backups7-sd: job.c:320-0 === Auth OK, unblock Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 jid=73 sd_ver=0
16-Out-2017 17:20:30 storage-backups7-sd: job.c:257-73 === Auth=73 jid=1 canceled=0 errstat=0
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:110-73 Start run Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:121-73 ==== JobType=B run_job=73 sd_client=0
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:145-73 Do: do_client_commands
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:183-73 <filed: append open session
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:266-73 Append open session: append open session
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:277-73 >filed: 3000 OK open ticket = 1
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:183-73 <filed: append data 1
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:226-73 Append data: append data 1
16-Out-2017 17:20:30 storage-backups7-sd: fd_cmds.c:228-73 <bfiled: append data 1
16-Out-2017 17:20:30 storage-backups7-sd: append.c:60-73 Start append data. res=1
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:273-73 Lock_acquire from acquire.c:378
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from acquire.c:379 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:381-73 acquire_append device is tape
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:211-73 rLock blked=BST_NOT_BLOCKED from acquire.c:414
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:399-73 set blocked=BST_DOING_ACQUIRE from acquire.c:415
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:165-73 Unlock from acquire.c:416 postcnt=0
16-Out-2017 17:20:30 storage-backups7-sd: acquire.c:417-73 jid=73 Do mount_next_write_vol
16-Out-2017 17:20:30 storage-backups7-sd: mount.c:68-73 Enter mount_next_volume(release=0) dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: mount.c:80-73 mount_next_vol retry=0
16-Out-2017 17:20:30 storage-backups7-sd: mount.c:616-73 No swap_dev set. dev->vol=0
16-Out-2017 17:20:30 storage-backups7-sd: mount.c:341-73 Before dir_find_next_appendable_volume.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:258-73 dir_find_next_appendable_volume: reserved=1 Vol=
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes at askdir.c:266 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes: got lock
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:276-73 >dird CatReq Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 FindMedia=1 pool_name=Pool-Backup-Diario media_type=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:172-73 <dird 1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:184-73 get_volume_info failed: ERR=1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:310-73 No vol. index 1 return false. dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:318-73 unlock_volumes at askdir.c:318 precnt=1
16-Out-2017 17:20:30 storage-backups7-sd: mount.c:343-73 not dir_find_next
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:551-73 enter dir_ask_sysop_to_create_appendable_volume
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:258-73 dir_find_next_appendable_volume: reserved=1 Vol=
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes at askdir.c:266 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:266-73 lock_volumes: got lock
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:276-73 >dird CatReq Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 FindMedia=1 pool_name=Pool-Backup-Diario media_type=LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:172-73 <dird 1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:184-73 get_volume_info failed: ERR=1901 No Media.
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:310-73 No vol. index 1 return false. dev="Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:318-73 unlock_volumes at askdir.c:318 precnt=1
16-Out-2017 17:20:30 storage-backups7-sd: askdir.c:578-73 Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 is waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "Device-Tape-Drive" (/dev/nst0)
    Pool:         Pool-Backup-Diario
    Media type:   LTO-4
16-Out-2017 17:20:30 storage-backups7-sd: lock.c:156-73 Lock from wait.c:50 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: wait.c:51-73 Enter blocked=BST_DOING_ACQUIRE
16-Out-2017 17:20:30 storage-backups7-sd: vol_mgr.c:681-73 vol_unused: no vol on "Device-Tape-Drive" (/dev/nst0)
16-Out-2017 17:20:30 storage-backups7-sd: vol_mgr.c:570-73 lock_volumes at vol_mgr.c:570 precnt=0
16-Out-2017 17:20:30 storage-backups7-sd: vol_mgr.c:570-73 lock_volumes: got lock
16-Out-2017 17:20:30 storage-backups7-sd: vol_mgr.c:577-73 unlock_volumes at vol_mgr.c:577 precnt=1
16-Out-2017 17:20:30 storage-backups7-sd: wait.c:79-73 blocked=BST_DOING_ACQUIRE
16-Out-2017 17:20:30 storage-backups7-sd: wait.c:92-73 I'm going to sleep on device "Device-Tape-Drive" (/dev/nst0). HB=60 rem_wait=3600 add_wait=60
16-Out-2017 17:21:30 storage-backups7-sd: wait.c:99-73 Wokeup from sleep on device stat=110 blocked=BST_WAITING_FOR_SYSOP
16-Out-2017 17:21:30 storage-backups7-sd: wait.c:110-73 Send heartbeat to FD.
16-Out-2017 17:21:30 storage-backups7-sd: wait.c:92-73 I'm going to sleep on device "Device-Tape-Drive" (/dev/nst0). HB=60 rem_wait=3540 add_wait=60
16-Out-2017 17:22:30 storage-backups7-sd: wait.c:99-73 Wokeup from sleep on device stat=110 blocked=BST_WAITING_FOR_SYSOP
16-Out-2017 17:22:30 storage-backups7-sd: wait.c:110-73 Send heartbeat to FD.
16-Out-2017 17:22:30 storage-backups7-sd: wait.c:92-73 I'm going to sleep on device "Device-Tape-Drive" (/dev/nst0). HB=60 rem_wait=3480 add_wait=60

Saída do bacula-fd
16-Out-2017 17:20:30 filedaemon-backups7-fd: bnet.c:566-0 who=client host=192.168.10.123 port=9102
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:494-0 Got: Hello Director director-backups7 calling 1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: Hello Director director-backups7 calling 1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir Hello Director director-backups7 calling 1
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:535-0 Calling Authenticate
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:60-0 send: auth cram-md5 challenge <1253678345.1508181630@filedaemon-backups7-fd> ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:119-0 cram-get received: auth cram-md5 <1039315189.1508181630@director-backups7> ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:139-0 sending resp to challenge: 8/+HB/9W7Q/3CiBdwz+8ND
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:539-0 OK Authenticate
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: JobId=73 Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 SDid=1 SDtime=1508181181 Authorization=PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir JobId=73 Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 SDid=1 SDtime=1508181181 Authorization=PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1840-0 set sd auth key PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:712-0 JobId=73 Auth=PIOA-JGMI-GHLC-EGGM-JENB-EEBM-OKOG-MBMA
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:1209-0 Instantiate plugin_ctx=7f7b1400c4a8 JobId=73
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:236-0 plugin_ctx=7f7b1400c4a8 JobId=73
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:129-0 name=<NULL> len=0 plugin=bpipe-fd.so plen=5
16-Out-2017 17:20:30 filedaemon-backups7-fd: bpipe-fd.c:216-0 bpipe-fd: JobStart=JobId=73 Job=Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: level = full  mtime_only=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir level = full  mtime_only=0
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1675-0 level_cmd: level = full  mtime_only=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:236-0 plugin_ctx=7f7b1400c4a8 JobId=73
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:129-0 name=<NULL> len=0 plugin=bpipe-fd.so plen=5
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: fileset vss=1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir fileset vss=1
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 I
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 O MZ6
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 N
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 F /backups1/gateway-ssh
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 N
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1252-0 N
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:236-0 plugin_ctx=7f7b1400c4a8 JobId=73
16-Out-2017 17:20:30 filedaemon-backups7-fd: fd_plugins.c:129-0 name=<NULL> len=0 plugin=bpipe-fd.so plen=5
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: storage address=backups7.fakedomain.br port=9103 ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir storage address=backups7.fakedomain.br port=9103 ssl=0
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1855-0 StorageCmd: storage address=backups7.fakedomain.br port=9103 ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1883-0 Connect to storage: backups7.fakedomain.br:9103 ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: bsock.c:208-0 Current [fe80::7ae3:b5ff:fe17:7e36]:9103 All [fe80::7ae3:b5ff:fe17:7e36]:9103 192.168.10.123:65535
16-Out-2017 17:20:30 filedaemon-backups7-fd: bsock.c:208-0 Current 192.168.10.123:9103 All [fe80::7ae3:b5ff:fe17:7e36]:9103 192.168.10.123:9103
16-Out-2017 17:20:30 filedaemon-backups7-fd: bsock.c:137-0 who=Daemon de armazenamento host=backups7.fakedomain.br port=9103
16-Out-2017 17:20:30 filedaemon-backups7-fd: bsock.c:310-0 OK connected to server  Daemon de armazenamento backups7.fakedomain.br:9103.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1899-0 Connection OK to SD.
16-Out-2017 17:20:30 filedaemon-backups7-fd: authenticate.c:237-0 Send to SD: Hello Bacula SD: Start Job Job-Backup-Gateway-SSH-Fita.2017-10-16_17.20.27_03 5

16-Out-2017 17:20:30 filedaemon-backups7-fd: authenticate.c:240-0 ==== respond to SD challenge
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:119-0 cram-get received: auth cram-md5 <2125351066.1508181630@storage-backups7-sd> ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:139-0 sending resp to challenge: A+AsaR+HZw+rU8lWFVAGfC
16-Out-2017 17:20:30 filedaemon-backups7-fd: authenticate.c:250-0 ==== Challenge SD
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:67-0 send: auth cram-md5 challenge <1936745421.1508181630@filedaemon-backups7-fd> ssl=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: cram-md5.c:86-0 Authenticate OK k6+rGCBP45+ev7IMiR/l5B
16-Out-2017 17:20:30 filedaemon-backups7-fd: authenticate.c:262-0 Authorization with SD is OK
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1944-0 Authenticated with SD.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:280-0 <dird: backup FileIndex=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:303-0 Executing Dir backup FileIndex=0
 command.
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1968-0 JobFiles=0
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1986-0 begin backup ff=7f7b1400ba78
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:1995-0 filed>dird: 2000 OK backup
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:2001-0 >stored: append open session
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:2006-0 <stored: 3000 OK open ticket = 1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:2011-0 Got Ticket=1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:2021-0 >stored: append data 1
16-Out-2017 17:20:30 filedaemon-backups7-fd: job.c:2026-0 <stored: append data 1

          Os emails do bacula tem vindo com essa mensagem:

2017-10-16_17.20.27_03 is waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "Device-Tape-Drive" (/dev/nst0)
    Pool:         Pool-Backup-Diario
    Media type:   LTO-4

          Alguém tem alguma luz, o que eu errei, sou novato no bacula, e não faço mais ideia do que fazer para resolver esse problema.

Muito obrigado


* Os nomes de hosts e IPs foram alterados

Heitor Faria

unread,
Oct 16, 2017, 6:16:30 PM10/16/17
to bacula-ba...@googlegroups.com
Boa pessoas, 
Olá, Rodrigo,
            Estou configurando um servidor bacula para utilizar uma biblioteca de fitas para os servidores de backups atuais.

            Falando um pouco do cenário atual.

            Atualmente possuo 6 servidores de backup que estão funcionais e estou montando o sétimo servidor de backup, esse servidor será a biblioteca de fitas que irá salvar os backups em fitas, e nesse servidor (HP ProLiant ML350 G6  Xeon E5...@2.40GHz) esta ligado um Dell PowerVault TL1000 Tape Library de 9 Fitas.
             Esse servidor esta utilizando o bacula 7.0.5 no ubuntu 16.04.3, instalado via repositório.

            Fiz alguns testes usando o tar direto e funcionou, então a biblioteca esta funcionando, apartir disso comecei a configurar o bacula.

            O servidor que tem as fitas esta com o os demais servidores montados via nfs como abaixo:
Pode parecer preciosismo, mas já rolei seu email 3 vezes e fiquei com preguiça de rolar tanta informação. Você falou de NFS e não focou no problema que é a questão do robô.
Próxima vez sugiro que você poste suas configurações e depurações num serviço tipo Pastebin. Aqui só posta a URL.
Segue o que consegui identificar:

"Autochanger {
Name = Autochanger
Device = Device-Tape-Drive, Device-Disk-Local
Changer Command = "/usr/sbin/mtx %c %o %S %a %d"
Changer Device = /dev/sg2
}"

Essa configuração que você fez não existe. Um Autochanger só deve ter devices de um mesmo tipo (drives de fita de um mesmo robô ou cabeças de gravação virtuais em um mesmo ponto de montagem, no caso de disco). Não é possível misturar.

Abraços,
--
Você recebeu essa mensagem porque está inscrito no grupo "Bacula Backup-Pt-Br" dos Grupos do Google.
Para cancelar inscrição nesse grupo e parar de receber e-mails dele, envie um e-mail para bacula-backup-p...@googlegroups.com.
Para postar nesse grupo, envie um e-mail para bacula-ba...@googlegroups.com.
Acesse esse grupo em https://groups.google.com/group/bacula-backup-pt-br.
Para ver essa discussão na Web, acesse https://groups.google.com/d/msgid/bacula-backup-pt-br/f7960c07-e8e2-4544-87fa-03951e8b8574%40googlegroups.com.
Para mais opções, acesse https://groups.google.com/d/optout.

--
===========================================================================
Heitor Medrado de Faria | CEO Bacula do Brasil | Visto EB-1 | LPIC-III | EMC 05-001 | ITIL-F
• 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/
============================================================================
Indicamos também as capacitações complementares:
• Shell básico e Programação em Shell com Julio Neves.
• Zabbix com Adail Host.
============================================================================
Reply all
Reply to author
Forward
0 new messages