I'm not sure if this is a configuration problem or not.  The problem recurred later in the day when the pool filled up.

On Sep 6, 2015, at 11:16 AM, Dan Langille <dan AT langille DOT org> wrote:

On Sep 6, 2015, at 9:51 AM, Dan Langille <dan AT langille DOT org> wrote:

This is a long post but contains details which might hint at the cause of the problem.

The backups have resumed, but the underlying problem persists.

On Sep 4, 2015, at 3:34 PM, Dan Langille <dan AT langille DOT org> wrote:

On Sep 3, 2015, at 12:26 AM, Ana Emília M. Arruda <emiliaarruda AT gmail DOT com> wrote:

Hello Dan,

On Wed, Sep 2, 2015 at 11:06 PM, Dan Langille <dan AT langille DOT org> wrote:

On Aug 25, 2015, at 6:55 PM, Ana Emília M. Arruda <emiliaarruda AT gmail DOT com> wrote:

Hello Dan,

Heitor is right :)

Thank you.

Ana: what are you using to quickly test these configurations?  I suspect a VM or similar.

​You're welcome. Yes, I have VMs for testing purposes :).​

 

I run tests with exactly your configuration and had the same issue. The drive index 0 must exist and belongs to the autochanger resource. You have an Index 0 drive in your configuration: vRestore. But it is not in the Autochanger definition. If you put it there, it will work:

Autochanger {
	Name = VirtualDisk
	Changer Device = /dev/null
	Changer Command = /dev/null
	Device = vRestore, vDrive-1, vDrive-2, vDrive-3, vDrive-4, vDrive-5
	}

I tried defining the vRestore drive with "autoselect = no" (this work for physical autochangers). But it seems to make no sense when dealing with virtual disk autochangers.

Do we need vRestore in there?  Instead, I renumbered my Drive Index fields from zero.

​No, I just added it to the device list because vRestore was configured as an autochanger drive with index 0. I think your solution is better, since we cannot select a specific drive for restores in the case of virtual autochangers.

 

Autochanger {

  Name = VirtualDisk

  Changer Device  = /dev/null

  Changer Command = /dev/null

  Device          = vDrive-1, vDrive-2, vDrive-3, vDrive-4, vDrive-5

}

FYI, I made a change.  I now use vDrive-0 .. vDrive-4

I renamed the devices to match the drive indexes. There is no technical reason for this.  I did it because I like the matching numbers.

Device {

  Name           = vDrive-1

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 0

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-2

...same as above

  Autochanger    = yes

  Drive Index    = 1

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-3

...same as above

  Autochanger    = yes

  Drive Index    = 2

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-4

...same as above

  Autochanger    = yes

  Drive Index    = 3

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-5

...same as above

  Autochanger    = yes

  Drive Index    = 4

  Maximum Concurrent Jobs = 25

  Volume Poll Interval    = 15

}

Having a single drive for restores worked for me. I was able to restore files from jobs/volumes that used the autochanger for backup.

 

​​ Device {
	Name = Restore ​-Drive​
	Media Type = File
	Archive Device = /usr/local/bacula/volumes
	LabelMedia = yes
	Random Access = yes
	AutomaticMount = yes
	RemovableMedia = yes
	AlwaysOpen = yes ​​

}

I have this in my bacula-sd.conf:

Device {

  Name           = Restore-Drive

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

When I run a restore job, it selects vDrive-1 automatically.

​Yes, if you select the virtual autochanger storage for restore, the drive index 0 will be selected for the restore.​

 

How did you get the restore to use Restore-Drive?

I configured the Restore-Drive as a storage in bacula-dir.conf (both Restore-Drive and VirtualDisk drives configured with the same archive device). This way we can choose the Restore-Drive for restoring files that are in volumes in the same mount point as the virtual autochanger.

That is what I suspected, but I wanted to make sure I did not misunderstand.  I've done the same and can restore from the Restore-Drive.

Today, being the first Sunday of the month, full backups are underway.  The original problem has returned.

06-Sep 07:05 crey-sd JobId 216727: New volume "FullAuto-3234" mounted on device "vDrive-2" (/usr/local/bacula/volumes) at 06-Sep-2015 07:05.

06-Sep 07:09 crey-sd JobId 216721: End of medium on Volume "FullAuto-3233" Bytes=5,368,688,700 Blocks=83,220 at 06-Sep-2015 07:09.

06-Sep 07:09 bacula-dir JobId 216721: There are no more Jobs associated with Volume "FullAuto-3235". Marking it purged.

06-Sep 07:09 bacula-dir JobId 216721: All records pruned from Volume "FullAuto-3235"; marking it "Purged"

06-Sep 07:09 bacula-dir JobId 216721: Recycled volume "FullAuto-3235"

06-Sep 07:09 crey-sd JobId 216721: Recycled volume "FullAuto-3235" on file device "vDrive-3" (/usr/local/bacula/volumes), all previous data lost.

06-Sep 07:09 crey-sd JobId 216721: New volume "FullAuto-3235" mounted on device "vDrive-3" (/usr/local/bacula/volumes) at 06-Sep-2015 07:09.

06-Sep 07:13 crey-sd JobId 216727: End of medium on Volume "FullAuto-3234" Bytes=5,368,688,757 Blocks=83,220 at 06-Sep-2015 07:13.

06-Sep 07:14 crey-sd JobId 216727: Job zuul_jail_snapshots.2015-09-06_03.05.47_33 is waiting. Cannot find any appendable volumes.

Please use the "label" command to create a new Volume for:

    Storage:      "vDrive-2" (/usr/local/bacula/volumes)

    Pool:         FullFile

    Media type:   File

06-Sep 07:19 crey-sd JobId 216721: End of medium on Volume "FullAuto-3235" Bytes=5,368,688,722 Blocks=83,220 at 06-Sep-2015 07:19.

06-Sep 07:20 crey-sd JobId 216721: Job slocum_jail_snapshots.2015-09-06_03.05.17_27 is waiting. Cannot find any appendable volumes.

Please use the "label" command to create a new Volume for:

    Storage:      "vDrive-3" (/usr/local/bacula/volumes)

    Pool:         FullFile

    Media type:   File

...

I amended the pool definition, increasing 'Maximum Volumes'.  In bconsole, I issued a reload and:

 $ bconsole 

Connecting to Director bacula.int.unixathome.org:9101

1000 OK: 1 bacula-dir Version: 7.0.5 (28 July 2014)

Enter a period to cancel a command.

*m

You have no messages.

*reload

*m

You have no messages.

*mount

Automatically selected Catalog: MyCatalog

Using Catalog "MyCatalog"

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Connecting to Storage daemon CreyFile at crey.int.unixathome.org:9103 ...

Enter autochanger drive[0]: 

Enter autochanger slot: 

3906 File device ""vDrive-0" (/usr/local/bacula/volumes)" is always mounted.

*m

You have no messages.

*m

06-Sep 11:54 bacula-dir JobId 216721: Created new Volume="FullAuto-4875", Pool="FullFile", MediaType="File" in catalog.

06-Sep 11:54 bacula-dir JobId 0: Max configured use duration=86,400 sec. exceeded. Marking Volume "TwoHourly-4395" as Used.

06-Sep 11:54 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4875" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4875,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 11:54 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4875" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4875,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 11:54 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4875" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4875,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 11:54 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4875" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4875,OPEN_READ_WRITE,0640): ERR=No such file or directory

It is having a similar problem with another volume creation:

06-Sep 11:00 crey-sd JobId 216727: Job zuul_jail_snapshots.2015-09-06_03.05.47_33 is waiting. Cannot find any appendable volumes.

Please use the "label" command to create a new Volume for:

    Storage:      "vDrive-2" (/usr/local/bacula/volumes)

    Pool:         FullFile

    Media type:   File

06-Sep 11:55 bacula-dir JobId 216727: Created new Volume="FullAuto-4876", Pool="FullFile", MediaType="File" in catalog.

06-Sep 11:55 crey-sd JobId 216727: Warning: mount.c:212 Open of file device "vDrive-2" (/usr/local/bacula/volumes) Volume "FullAuto-4876" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4876,OPEN_READ_WRITE,0640): ERR=No such file or directory

I have confirmed the Catalog contains the Volume: 

bacula=# select mediaid, mediatype from media where volumename = 'FullAuto-4875';

 mediaid | mediatype 

---------+-----------

    4875 | File

(1 row)

However, it is not on disk: 

[dan@crey:~] $ ls -ld /usr/local/bacula/volumes

drwxr-xr-x  3 bacula  bacula  2706 Aug 30 11:22 /usr/local/bacula/volumes

[dan@crey:~] $

$ ls -ld /usr/local/bacula/volumes/FullAuto-487*

ls: /usr/local/bacula/volumes/FullAuto-487*: No such file or directory

Disk space seems to not be the cause:

[dan@crey:~] $ df -h

Filesystem               Size    Used   Avail Capacity  Mounted on

system/usr/jails/crey    3.8T    820M    3.8T     0%    /

Then I tried another mount, this time, with incorrect values:

*mount

Automatically selected Catalog: MyCatalog

Using Catalog "MyCatalog"

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Enter autochanger drive[0]: 2

Enter autochanger slot: 2

3001 OK mount requested. Specified slot ignored. Device="vDrive-2" (/usr/local/bacula/volumes)

You have messages.

*m

06-Sep 13:03 crey-sd JobId 216727: Labeled new Volume "FullAuto-4876" on file device "vDrive-2" (/usr/local/bacula/volumes).

06-Sep 13:03 crey-sd JobId 216727: Wrote label to prelabeled Volume "FullAuto-4876" on file device "vDrive-2" (/usr/local/bacula/volumes)

06-Sep 13:03 crey-sd JobId 216727: New volume "FullAuto-4876" mounted on device "vDrive-2" (/usr/local/bacula/volumes) at 06-Sep-2015 13:03.

*

But it continued to have trouble with FullAuto-4875

So I tried this, which failed:

*mount

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Enter autochanger drive[0]: 2

Enter autochanger slot: 2

3906 File device ""vDrive-2" (/usr/local/bacula/volumes)" is always mounted.

*m

06-Sep 13:39 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4875" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4875,OPEN_READ_WRITE,0640): ERR=No such file or directory

Try a different approach:

*mount

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 2

3906 File device ""Restore-Drive" (/usr/local/bacula/volumes)" is always mounted.

No, that didn't mount.

BUT THEN THIS WORKED:

*mount

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Enter autochanger drive[0]: 3

Enter autochanger slot: 2

3001 OK mount requested. Specified slot ignored. Device="vDrive-3" (/usr/local/bacula/volumes)

*m

You have no messages.

*m

You have no messages.

*m

06-Sep 13:39 crey-sd JobId 216721: Labeled new Volume "FullAuto-4875" on file device "vDrive-3" (/usr/local/bacula/volumes).

06-Sep 13:39 crey-sd JobId 216721: Wrote label to prelabeled Volume "FullAuto-4875" on file device "vDrive-3" (/usr/local/bacula/volumes)

06-Sep 13:39 crey-sd JobId 216721: New volume "FullAuto-4875" mounted on device "vDrive-3" (/usr/local/bacula/volumes) at 06-Sep-2015 13:39.

*

FYI, later in the day, things worked OK:

06-Sep 15:03 crey-sd JobId 216721: End of medium on Volume "FullAuto-4879" Bytes=5,368,688,700 Blocks=83,220 at 06-Sep-2015 15:03.

06-Sep 15:03 bacula-dir JobId 216721: Created new Volume="FullAuto-4880", Pool="FullFile", MediaType="File" in catalog.

06-Sep 15:04 crey-sd JobId 216721: Labeled new Volume "FullAuto-4880" on file device "vDrive-3" (/usr/local/bacula/volumes).

06-Sep 15:04 crey-sd JobId 216721: Wrote label to prelabeled Volume "FullAuto-4880" on file device "vDrive-3" (/usr/local/bacula/volumes)

06-Sep 15:04 crey-sd JobId 216721: New volume "FullAuto-4880" mounted on device "vDrive-3" (/usr/local/bacula/volumes) at 06-Sep-2015 15:04.

It seems that once you run out of Volumes, things get confused.... but once it picks up again, all is OK.

In that case, I specified 'autochanger drive' 3 and slot 2.

More of the above can be found at https://gist.github.com/dlangille/35ff347d0ad37e799015

Configuration:

Storage {                             # definition of myself

  Name = crey-sd

  WorkingDirectory = "/usr/local/bacula/working"

  Pid Directory = "/var/run"

  Maximum Concurrent Jobs = 20

  TLS Enable      = yes

  TLS Verify Peer = yes

  TLS CA Certificate File = /usr/local/etc/ssl/ca.pem

  TLS Certificate = /usr/local/etc/ssl/crey.int.unixathome.org.crt

  TLS Key         = /usr/local/etc/ssl/crey.int.unixathome.org.nopassword.key

}

#

# List Directors who are permitted to contact Storage daemon

#

Director {

  Name = bacula-dir

  Password = "redacted"

}

Device {

  Name           = Restore-Drive

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Maximum Concurrent Jobs = 2

  Volume Poll Interval    = 15

}

Autochanger {

  Name = VirtualDisk

  Changer Device  = /dev/null

  Changer Command = /dev/null

  Device          = vDrive-0, vDrive-1, vDrive-2, vDrive-3, vDrive-4

}

Device {

  Name           = vDrive-0

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 0

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-1

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 1

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-2

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 2

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-3

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 3

  Maximum Concurrent Jobs = 1

  Volume Poll Interval    = 15

}

Device {

  Name           = vDrive-4

  Media Type     = File

  Archive Device = /usr/local/bacula/volumes

  LabelMedia     = yes

  Random Access  = yes

  AutomaticMount = yes

  RemovableMedia = no

  AlwaysOpen     = no

  Autochanger    = yes

  Drive Index    = 4

  Maximum Concurrent Jobs = 25

  Volume Poll Interval    = 15

}

Messages {

  Name = Standard

  director = bacula-dir = all

}

The problem is reproducible.

*m

06-Sep 21:40 crey-sd JobId 216721: Job slocum_jail_snapshots.2015-09-06_03.05.17_27 is waiting. Cannot find any appendable volumes.

Please use the "label" command to create a new Volume for:

    Storage:      "vDrive-3" (/usr/local/bacula/volumes)

    Pool:         FullFile

    Media type:   File

06-Sep 21:40 bacula-dir JobId 216715: Start Backup JobId 216715, Job=supernews.2015-09-06_03.05.15_21

06-Sep 21:41 bacula-dir JobId 216715: Using Device "vDrive-0" to write.

06-Sep 21:41 crey-sd JobId 216715: Job supernews.2015-09-06_03.05.15_21 is waiting. Cannot find any appendable volumes.

Please use the "label" command to create a new Volume for:

    Storage:      "vDrive-0" (/usr/local/bacula/volumes)

    Pool:         FullFile

    Media type:   File

I amended the pool to increase Max # Volumes for the pool.  Then:

*reload

*m

06-Sep 21:44 bacula-dir JobId 216715: Created new Volume="FullAuto-4905", Pool="FullFile", MediaType="File" in catalog.

06-Sep 21:44 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

NOTE: it goes to try creating another Volume:

06-Sep 21:44 bacula-dir JobId 216721: Created new Volume="FullAuto-4906", Pool="FullFile", MediaType="File" in catalog.

06-Sep 21:44 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:44 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:45 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:45 crey-sd JobId 216721: Warning: mount.c:212 Open of file device "vDrive-3" (/usr/local/bacula/volumes) Volume "FullAuto-4906" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4906,OPEN_READ_WRITE,0640): ERR=No such file or directory

If I mount ON THE DRIVE it is looking for, it proceeds:

*mount

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Connecting to Storage daemon CreyFile at crey.int.unixathome.org:9103 ...

Enter autochanger drive[0]: 3

Enter autochanger slot: 0

3001 OK mount requested. Device="vDrive-3" (/usr/local/bacula/volumes)

You have messages.

*m

06-Sep 21:46 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:46 crey-sd JobId 216721: Labeled new Volume "FullAuto-4906" on file device "vDrive-3" (/usr/local/bacula/volumes).

06-Sep 21:46 crey-sd JobId 216721: Wrote label to prelabeled Volume "FullAuto-4906" on file device "vDrive-3" (/usr/local/bacula/volumes)

06-Sep 21:46 crey-sd JobId 216721: New volume "FullAuto-4906" mounted on device "vDrive-3" (/usr/local/bacula/volumes) at 06-Sep-2015 21:46.

*

Let's try that theory again, on drive 0, which is where it's waiting:

*m

06-Sep 21:47 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

06-Sep 21:48 crey-sd JobId 216715: Warning: mount.c:212 Open of file device "vDrive-0" (/usr/local/bacula/volumes) Volume "FullAuto-4905" failed: ERR=file_dev.c:172 Could not open(/usr/local/bacula/volumes/FullAuto-4905,OPEN_READ_WRITE,0640): ERR=No such file or directory

*mount

The defined Storage resources are:

     1: CreyFile

     2: CreyFileRestore

     3: CompaqStorageWorksTapeLibrary

Select Storage resource (1-3): 1

Enter autochanger drive[0]: 0

Enter autochanger slot: 0

3001 OK mount requested. Device="vDrive-0" (/usr/local/bacula/volumes)

*m

06-Sep 21:48 crey-sd JobId 216715: Labeled new Volume "FullAuto-4905" on file device "vDrive-0" (/usr/local/bacula/volumes).

06-Sep 21:48 crey-sd JobId 216715: Wrote label to prelabeled Volume "FullAuto-4905" on file device "vDrive-0" (/usr/local/bacula/volumes)

*

It works again.

Storage status:

*st st

Automatically selected Storage: CreyFile

Connecting to Storage daemon CreyFile at crey.int.unixathome.org:9103

crey-sd Version: 7.0.5 (28 July 2014) amd64-portbld-freebsd10.2 freebsd 10.2-RELEASE

Daemon started 04-Sep-15 19:21. Jobs: run=75, running=1.

 Heap: heap=0 smbytes=1,596,775 max_bytes=2,476,847 bufs=295 max_bufs=409

 Sizes: boffset_t=8 size_t=8 int32_t=4 int64_t=8 mode=0,0

Running Jobs:

Writing: Full Backup job slocum_jail_snapshots JobId=216721 Volume="FullAuto-4906"

    pool="FullFile" device="vDrive-3" (/usr/local/bacula/volumes)

    spooling=0 despooling=0 despool_wait=0

    Files=911,814 Bytes=239,502,099,426 AveBytes/sec=5,464,380 LastBytes/sec=5,473,595

    FDReadSeqNo=8,657,143 in_msg=6945579 out_msg=6 fd=19

Writing: Full Backup job supernews JobId=216715 Volume="FullAuto-4905"

    pool="FullFile" device="vDrive-0" (/usr/local/bacula/volumes)

    spooling=0 despooling=0 despool_wait=0

    Files=1,313 Bytes=73,874,694 AveBytes/sec=2,308,584 LastBytes/sec=2,308,584

    FDReadSeqNo=12,619 in_msg=8717 out_msg=6 fd=7

====

Jobs waiting to reserve a drive:

====

Terminated Jobs:

 JobId  Level    Files      Bytes   Status   Finished        Name 

===================================================================

216707  Full          0         0   OK       06-Sep-15 03:55 gelt_freshports_dev

216709  Full    381,907    83.27 G  OK       06-Sep-15 04:53 knew_jail_snapshots

216733  Incr        391    116.1 M  OK       06-Sep-15 12:03 mailjail_snapshot

216727  Full    497,384    126.0 G  OK       06-Sep-15 13:13 zuul_jail_snapshots

216734  Incr        191    87.04 M  OK       06-Sep-15 14:02 mailjail_snapshot

216735  Incr        261    90.91 M  OK       06-Sep-15 16:02 mailjail_snapshot

216736  Incr        111    70.74 M  OK       06-Sep-15 18:01 mailjail_snapshot

216737  Incr        177    79.18 M  OK       06-Sep-15 20:01 mailjail_snapshot

216713  Full   1,558,273    6.635 G  OK       06-Sep-15 20:54 supernews_FP_msgs

216714  Full      4,741    371.0 M  OK       06-Sep-15 21:34 supernews_basic

====

Device status:

Autochanger "VirtualDisk" with devices:

   "vDrive-0" (/usr/local/bacula/volumes)

   "vDrive-1" (/usr/local/bacula/volumes)

   "vDrive-2" (/usr/local/bacula/volumes)

   "vDrive-3" (/usr/local/bacula/volumes)

   "vDrive-4" (/usr/local/bacula/volumes)

Autochanger "Compaq Storage Works MSL5026" with devices:

   "DTL03" (/dev/nsa0)

Device "Restore-Drive" (/usr/local/bacula/volumes) is not open.

==

Device "vDrive-0" (/usr/local/bacula/volumes) is mounted with:

    Volume:      FullAuto-4905

    Pool:        FullFile

    Media type:  File

    Drive 0 is not loaded.

    Total Bytes=73,930,947 Blocks=1,146 Bytes/block=64,512

    Positioned at File=0 Block=73,930,946

==

Device "vDrive-1" (/usr/local/bacula/volumes) is not open.

    Drive 1 is not loaded.

==

Device "vDrive-2" (/usr/local/bacula/volumes) is not open.

    Drive 2 is not loaded.

==

Device "vDrive-3" (/usr/local/bacula/volumes) is mounted with:

    Volume:      FullAuto-4906

    Pool:        FullFile

    Media type:  File

    Drive 3 is not loaded.

    Total Bytes=4,499,196,004 Blocks=69,742 Bytes/block=64,512

    Positioned at File=1 Block=204,228,707

==

Device "vDrive-4" (/usr/local/bacula/volumes) is not open.

    Drive 4 is not loaded.

==

Device "DTL03" (/dev/nsa0) is mounted with:

    Volume:      FAI078

    Pool:        *unknown*

    Media type:  SDLT

    Slot 17 is loaded in drive 0.

    Total Bytes Read=0 Blocks Read=0 Bytes/block=0

    Positioned at File=0 Block=0

==

====

Used Volume status:

Reserved volume: FAI078 on tape device "DTL03" (/dev/nsa0)

    Reader=0 writers=0 reserves=0 volinuse=0

Reserved volume: FullAuto-4905 on file device "vDrive-0" (/usr/local/bacula/volumes)

    Reader=0 writers=1 reserves=0 volinuse=1

Reserved volume: FullAuto-4906 on file device "vDrive-3" (/usr/local/bacula/volumes)

    Reader=0 writers=1 reserves=0 volinuse=1

====

Attr spooling: 2 active jobs, 0 bytes; 68 total jobs, 574,172,670 max bytes.

====

*

— 

Dan Langille

http://langille.org/

------------------------------------------------------------------------------

_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users