cannot create volume after update

[Víťa Čapek]

Hi,

after update from Bacularis 3.2.1 to 4.0.0 via rpm repositories cannot create new volume on disk.

Here is full job log:

backup-sd JobId 40411: Marking Volume "FullPool-40137" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40137" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,OPEN_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-casa-dir JobId 40411: Created new Volume="FullPool-40137", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40411: Please mount append Volume "FullPool-40136" or label a new one for:
    Job:          www2.netreal.casa.local-system.2024-10-02_13.45.45_33
    Storage:      "FileStorage" (/mnt/backup-raid5/bacula/)
    Pool:         FullPool
    Media type:   File
backup-sd JobId 40411: Marking Volume "FullPool-40136" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40136" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40136" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40136" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Cartridge change or "update slots" may be required.
backup-sd JobId 40411: No slot defined in catalog (slot=0) for Volume "FullPool-40136" on "FileStorage" (/mnt/backup-raid5/bacula/).
backup-casa-dir JobId 40411: Created new Volume="FullPool-40136", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40411: Marking Volume "FullPool-40135" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40135" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40135" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40135" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Cartridge change or "update slots" may be required.
backup-sd JobId 40411: No slot defined in catalog (slot=0) for Volume "FullPool-40135" on "FileStorage" (/mnt/backup-raid5/bacula/).
backup-casa-dir JobId 40411: Created new Volume="FullPool-40135", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40411: Marking Volume "FullPool-40134" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40134" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40134" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40134" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Cartridge change or "update slots" may be required.
backup-sd JobId 40411: No slot defined in catalog (slot=0) for Volume "FullPool-40134" on "FileStorage" (/mnt/backup-raid5/bacula/).
backup-casa-dir JobId 40411: Created new Volume="FullPool-40134", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40411: Marking Volume "FullPool-40133" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40133" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40133" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40133" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Cartridge change or "update slots" may be required.
backup-sd JobId 40411: No slot defined in catalog (slot=0) for Volume "FullPool-40133" on "FileStorage" (/mnt/backup-raid5/bacula/).
backup-casa-dir JobId 40411: Created new Volume="FullPool-40133", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40411: Marking Volume "FullPool-40132" in Error in Catalog.
backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40132" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40132" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40132" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a directory
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
Insufficient number of arguments given.
  Mimimum usage is first two arguments ...
backup-sd JobId 40411: Cartridge change or "update slots" may be required.
backup-sd JobId 40411: No slot defined in catalog (slot=0) for Volume "FullPool-40132" on "FileStorage" (/mnt/backup-raid5/bacula/).
www2.netreal.casa.local JobId 40411: Connected to Storage at backup.casa.local:9103 with TLS
backup-casa-dir JobId 40411: Connected to Client "www2.netreal.casa.local" at 172.16.0.12:9102 with TLS
backup-casa-dir JobId 40411: Using Device "FileStorage" to write.
backup-casa-dir JobId 40411: Created new Volume="FullPool-40132", Pool="FullPool", MediaType="File" in catalog.
backup-casa-dir JobId 40411: Connected to Storage "backup" at backup.casa.local:9103 with TLS
backup-casa-dir JobId 40411: Start Backup JobId 40411, Job=www2.netreal.casa.local-system.2024-10-02_13.45.45_33

Could you please resolve my problem? 

Thank you Vita

[Marcin Haba]

On Wed, 2 Oct 2024 at 13:57, Víťa Čapek <vita...@gmail.com> wrote:
>
> Hi,
> after update from Bacularis 3.2.1 to 4.0.0 via rpm repositories cannot create new volume on disk.
> Here is full job log:
>
> backup-sd JobId 40411: Marking Volume "FullPool-40137" in Error in Catalog.
> backup-sd JobId 40411: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40137" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/,OPEN_READ_WRITE,0640): ERR=Is a directory
> backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command: ERR=Child exited with code 1.
> Results=ERROR: usage: mtx-changer ctl-device command [slot archive-device drive-index]
> Insufficient number of arguments given.
> Mimimum usage is first two arguments ...

Hello Vita,

Welcome to the Bacularis user group.

This error is reported by the storage daemon. Could you show us your
Autochanger resource config from the bacula-sd.conf file?

Thanks in advance.

Best regards,
Marcin Haba (gani)

[Víťa Čapek]

Hello,

thank you very much for you response.

Here is part of bacula-sd.conf:

Device {
  Name = "FileChgr1-Dev1"
  MediaType = "File1"
  ArchiveDevice = "/tmp"
  RemovableMedia = no
  RandomAccess = yes
  AutomaticMount = yes
  LabelMedia = yes
  AlwaysOpen = no
  MaximumConcurrentJobs = 5
}
Device {
  Name = "FileChgr1-Dev2"
  MediaType = "File1"
  ArchiveDevice = "/tmp"
  RemovableMedia = no
  RandomAccess = yes
  AutomaticMount = yes
  LabelMedia = yes
  AlwaysOpen = no
  MaximumConcurrentJobs = 5
}

Autochanger {
  Name = "FileChgr1"
  Device = "FileChgr1-Dev1"
  Device = "FileChgr1-Dev2"
  ChangerDevice = "/dev/null"
  ChangerCommand = ""
}

I have the same setup on the second Bacula server. The only difference is in the installation method. On the second server, Bacularis is installed via Compose.

Thank you Vita

Dne středa 2. října 2024 v 14:46:20 UTC+2 uživatel Marcin Haba napsal:

[Marcin Haba]

Hello Vita,

Thanks for showing us your SD config.

Now I have two other questions:

- What Bacula Director/SD do you have on these two hosts?

- Did you upgrade Bacula together with Bacularis on one of the hosts?

If your Bacula SD is 7.0.0 or higher then for the virtual autochanger
the ChangerCommand directive should be

instead of:

ChangerCommand = ""

should be:

ChangerCommand = "/dev/null"

It is the reason why I am asking about this Bacula version.

[Víťa Čapek]

The Bacula versions on both servers are the same. Below, I am adding the update log:

backup_server_1 (bacularis rpm based):

Upgrade       bacularis-4.0.0-1.el9.noarch 
Upgraded      bacularis-3.2.1-1.el9.noarch
Upgrade       bacularis-nginx-4.0.0-1.el9.noarch 
Upgraded      bacularis-nginx-3.2.1-1.el9.noarch   
Upgrade       bacularis-selinux-4.0.0-1.el9.noarch  
Upgraded      bacularis-selinux-3.2.1-1.el9.noarch

Upgrade       bacula-libs-13.0.4-24021221.el9.x86_64 
Upgraded      bacula-libs-13.0.2-23022019.el9.x86_64 
Upgrade       bacula-postgresql-13.0.4-24021221.el9.x86_64
Upgraded      bacula-postgresql-13.0.2-23022019.el9.x86_64 

backup_server_2 (compose install based)

Bacularis: Version: 4.2.1

Installed Packages
bacula-libs.x86_64                                         13.0.4-24021221.el8     
bacula-postgresql.x86_64                                   13.0.4-24021221.el8

I tried to modify the autochanger by adding /dev/null to ChangerCommand, and below I am attaching the log.

backup-sd JobId 40418: Marking Volume "FullPool-40187" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40187" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40187,OPEN_READ_WRITE,0640): ERR=Permission denied
backup-casa-dir JobId 40418: Created new Volume="FullPool-40187", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40418: Marking Volume "FullPool-40186" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40186" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40186,OPEN_READ_WRITE,0640): ERR=Permission denied
backup-casa-dir JobId 40418: Created new Volume="FullPool-40186", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40418: Marking Volume "FullPool-40185" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40185" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40185,OPEN_READ_WRITE,0640): ERR=Permission denied
backup-casa-dir JobId 40418: Created new Volume="FullPool-40185", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40418: Please mount append Volume "FullPool-40184" or label a new one for:
    Job:          www2.netreal.casa.local-system.2024-10-02_15.46.08_33

    Storage:      "FileStorage" (/mnt/backup-raid5/bacula/)
    Pool:         FullPool
    Media type:   File

backup-sd JobId 40418: Marking Volume "FullPool-40184" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40184" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40184,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40184" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40184,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40184" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40184,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-casa-dir JobId 40418: Created new Volume="FullPool-40184", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40418: Marking Volume "FullPool-40183" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40183" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40183,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40183" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40183,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40183" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40183,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-casa-dir JobId 40418: Created new Volume="FullPool-40183", Pool="FullPool", MediaType="File" in catalog.
backup-sd JobId 40418: Marking Volume "FullPool-40182" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40182" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40182,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40182" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40182,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40182" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40182,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Marking Volume "FullPool-40181" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40181" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40181,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40181" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40181,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40181" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40181,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Marking Volume "FullPool-40180" Read-Only in Catalog.
backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40180" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40180,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40180" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40180,CREATE_READ_WRITE,0640): ERR=Permission denied
backup-sd JobId 40418: Warning: label.c:404 Open File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40180" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40180,CREATE_READ_WRITE,0640): ERR=Permission denied
www2.netreal.casa.local JobId 40418: Connected to Storage at backup.casa.local:9103 with TLS
backup-casa-dir JobId 40418: Connected to Client "www2.netreal.casa.local" at 172.16.0.12:9102 with TLS
backup-casa-dir JobId 40418: Using Device "FileStorage" to write.
backup-casa-dir JobId 40418: Connected to Storage "backup" at backup.casa.local:9103 with TLS
backup-casa-dir JobId 40418: Start Backup JobId 40418, Job=www2.netreal.casa.local-system.2024-10-02_15.46.08_33

bacula-sd.conf

Autochanger {
  Name = "FileChgr1"
  Device = "FileChgr1-Dev1"
  Device = "FileChgr1-Dev2"
  ChangerDevice = "/dev/null"

  ChangerCommand = "dev/null"
}

Permissions on folder are bacula:disk. The problem remains the same, the volumes are not created on the disk at all. They are only in the Catalog.

Thank you

Dne středa 2. října 2024 v 15:36:28 UTC+2 uživatel Marcin Haba napsal:

[Marcin Haba]

On Wed, 2 Oct 2024 at 16:13, Víťa Čapek <vita...@gmail.com> wrote:
>
> The Bacula versions on both servers are the same. Below, I am adding the update log:
>
> backup_server_1 (bacularis rpm based):
>
> Upgrade bacularis-4.0.0-1.el9.noarch
> Upgraded bacularis-3.2.1-1.el9.noarch
> Upgrade bacularis-nginx-4.0.0-1.el9.noarch
> Upgraded bacularis-nginx-3.2.1-1.el9.noarch
> Upgrade bacularis-selinux-4.0.0-1.el9.noarch
> Upgraded bacularis-selinux-3.2.1-1.el9.noarch
> Upgrade bacula-libs-13.0.4-24021221.el9.x86_64
> Upgraded bacula-libs-13.0.2-23022019.el9.x86_64
> Upgrade bacula-postgresql-13.0.4-24021221.el9.x86_64
> Upgraded bacula-postgresql-13.0.2-23022019.el9.x86_64
>
> backup_server_2 (compose install based)
> Bacularis: Version: 4.2.1
>
> Installed Packages
> bacula-libs.x86_64 13.0.4-24021221.el8
> bacula-postgresql.x86_64 13.0.4-24021221.el8
>
> I tried to modify the autochanger by adding /dev/null to ChangerCommand, and below I am attaching the log.
>
> backup-sd JobId 40418: Marking Volume "FullPool-40187" Read-Only in Catalog.
> backup-sd JobId 40418: Warning: mount.c:216 Open of File device "FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40187" failed: ERR=file_dev.c:189 Could not open(/mnt/backup-raid5/bacula/FullPool-40187,OPEN_READ_WRITE,0640): ERR=Permission denied
> backup-casa-dir JobId 40418: Created new Volume="FullPool-40187", Pool="FullPool", MediaType="File" in catalog.
> backup-sd JobId 40418: Marking Volume "FullPool-40186" Read-Only in Catalog.

[cut]

> bacula-sd.conf
>
> Autochanger {
> Name = "FileChgr1"
> Device = "FileChgr1-Dev1"
> Device = "FileChgr1-Dev2"
> ChangerDevice = "/dev/null"
> ChangerCommand = "dev/null"
> }
>
>
> Permissions on folder are bacula:disk. The problem remains the same, the volumes are not created on the disk at all. They are only in the Catalog.

Hello Vita,

Thanks for providing more details.

It looks that there were two errors. This error after adding /dev/null has gone:

backup-sd JobId 40411: 3991 Bad autochanger "loaded? drive 0" command:
ERR=Child exited with code 1.
Results=ERROR: usage: mtx-changer ctl-device command [slot
archive-device drive-index]
Insufficient number of arguments given.
Mimimum usage is first two arguments ...

and it is correct because your Bacula version is 13.0, so the virtual
autochanger requires /dev/null there.

The second problem with permissions is still available. Now it has changed from:

backup-sd JobId 40411: Warning: label.c:404 Open File device
"FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40132"

failed: ERR=file_dev.c:189 Could not

open(/mnt/backup-raid5/bacula/,CREATE_READ_WRITE,0640): ERR=Is a
directory

into this:

backup-sd JobId 40418: Warning: label.c:404 Open File device
"FileStorage" (/mnt/backup-raid5/bacula/) Volume "FullPool-40184"
failed: ERR=file_dev.c:189 Could not
open(/mnt/backup-raid5/bacula/FullPool-40184,CREATE_READ_WRITE,0640):
ERR=Permission denied

The only thing that I don't understand is that your Autochanger
Devices paths are /tmp and the Device in the log is
/mnt/backup-raid5/bacula/

How does the Device resource look with this directive in the bacula-sd.conf?

Archive Device = "/mnt/backup-raid5/bacula/"

and how does the Storage resource look in the bacula-dir.conf?

For the permission error, I would propose to do a quick test. On the
root user account could you check if these commands are able to create
and remove a file?

# sudo -u bacula -g tape touch /mnt/backup-raid5/bacula/testfile
# sudo -u bacula -g tape rm /mnt/backup-raid5/bacula/testfile

This test is for checking if the SELinux context can cause any problem
here. If yes, then it might be good to look at
/var/log/audit/audit.log as well.

[Víťa Čapek]

Hello Marcin,

 
The issue was with the permissions for the `/mnt/backup-raid5` and `/mnt/backup-raid5/bacula` folders. The Bacula user did not have read and write permissions. It's strange because I didn't modify the permissions, and I had checked their settings. It wasn't until I tested reading as the Bacula user that I figured it out. Of course, thanks to your suggestion. Now everything works correctly. Thank you so much!

I want to take this opportunity to thank you for your amazing work for the Bacula community. Thanks to the Bacularis system, I had the courage to start using Bacula and replace rsnapshot. For me, it’s an indispensable tool for backups, and for my colleagues, a convenient way to restore data. I hope you continue your work. Do you have an account, for example, on buymeacoffee.com? I’d love to at least repay you this way for your work.  
Have a nice day,  
Vita

Dne středa 2. října 2024 v 17:45:37 UTC+2 uživatel Marcin Haba napsal:

[Marcin Haba]

On Thu, 3 Oct 2024 at 10:37, Víťa Čapek <vita...@gmail.com> wrote:
>
> Hello Marcin,
>
> The issue was with the permissions for the `/mnt/backup-raid5` and `/mnt/backup-raid5/bacula` folders. The Bacula user did not have read and write permissions. It's strange because I didn't modify the permissions, and I had checked their settings. It wasn't until I tested reading as the Bacula user that I figured it out. Of course, thanks to your suggestion. Now everything works correctly. Thank you so much!
>
> I want to take this opportunity to thank you for your amazing work for the Bacula community. Thanks to the Bacularis system, I had the courage to start using Bacula and replace rsnapshot. For me, it’s an indispensable tool for backups, and for my colleagues, a convenient way to restore data. I hope you continue your work. Do you have an account, for example, on buymeacoffee.com? I’d love to at least repay you this way for your work.
> Have a nice day,

Hello Vita,

Great to hear that you solved this permission problem.

Thank you for your kind words about Bacularis and my work :-) Yes,
Bacularis is exactly for this purpose - to help users use Bacula. I am
glad every time when this goal is achieved. It is very kind that you
would like to repay. For me the best repaying is when users give
feedback about Bacularis what you already did :-) Many thanks, Vita.