Bacula-users

[Bacula-users] Volume has been set to "FULL" and not "USED"

2012-11-07 09:05:09
Subject: [Bacula-users] Volume has been set to "FULL" and not "USED"
From: Mauro Colorio <mauro.colorio AT gmail DOT com>
To: bacula-users <bacula-users AT lists.sourceforge DOT net>
Date: Wed, 7 Nov 2012 15:00:18 +0100
I need some help,
I 've 5 volumes in a Daily pool


Pool {
  Name = Daily
  Pool Type = Backup
  Recycle = yes
  AutoPrune = yes
  Volume Retention = 5 days
  Maximum Volume Bytes = 800G
  Volume Use Duration = 23h
  Maximum Volumes = 5
}

so I can do everyday for 5 days a backup and this has worked out for
many months,
the past monday one of these volumes gone in write error  but  I
didn't received any mail about this, I just received this mail:


05-Nov 23:39 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is waiting.
Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4

so I looked at the volumes status and I see:


DAY001L4        Full    120 MB  800 GB  8       5       LTO-4   2012-11-06 
23:00:01     2012-11-07
06:46:02        4       +
DAY002L4        Used    409.9 GB        800 GB  7       5       LTO-4   
2012-11-02 23:02:41     2012-11-03
06:25:46        5       +
DAY003L4        Full    306.9 MB        800 GB  1       5       LTO-4   
2012-11-05 23:03:45     2012-11-05
23:21:29        6       +
DAY004L4        Full    884.9 MB        800 GB  1       5       LTO-4   
2012-11-05 23:03:45     2012-11-05
23:38:44        7       +
DAY005L4        Full    2 GB    800 GB  1       5       LTO-4   2012-11-05 
23:03:45     2012-11-06 06:57:50     8       +

in /var/lib/bacula I found this file

bacula-dir.RSYNC.2012-11-05_23.00.00_10.18513816.mail
===============================================================================================================
05-Nov 23:00 bacula-dir JobId 4130: Start Backup JobId 4130,
Job=RSYNC.2012-11-05_23.00.00_10
05-Nov 23:00 bacula-sd JobId 4130: 3307 Issuing autochanger "unload
slot 1, drive 0" command.
05-Nov 23:03 bacula-dir JobId 4130: There are no more Jobs associated
with Volume "DAY003L4". Marking it purged.
05-Nov 23:03 bacula-dir JobId 4130: All records pruned from Volume
"DAY003L4"; marking it "Purged"
05-Nov 23:03 bacula-dir JobId 4130: Recycled volume "DAY003L4"
05-Nov 23:03 bacula-dir JobId 4130: Using Device "HP-ULTRIUM"
05-Nov 23:03 bacula-sd JobId 4130: 3301 Issuing autochanger "loaded?
drive 0" command.
05-Nov 23:03 bacula-sd JobId 4130: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
05-Nov 23:03 bacula-sd JobId 4130: 3304 Issuing autochanger "load slot
6, drive 0" command.
05-Nov 23:04 bacula-sd JobId 4130: 3305 Autochanger "load slot 6,
drive 0", status is OK.
05-Nov 23:04 bacula-sd JobId 4130: Recycled volume "DAY003L4" on
device "HP-ULTRIUM" (/dev/nst0), all previous data lost.
05-Nov 23:06 bacula-sd JobId 4130: Error: block.c:577 Write error at
0:4988 on device "HP-ULTRIUM" (/dev/nst0). ERR=Input/output error.
05-Nov 23:21 bacula-sd JobId 4130: Error: Error writing final EOF to
tape. This Volume may not be readable.
dev.c:1745 ioctl MTWEOF error on "HP-ULTRIUM" (/dev/nst0).
ERR=Input/output error.
05-Nov 23:21 bacula-sd JobId 4130: End of medium on Volume "DAY003L4"
Bytes=321,785,856 Blocks=4,987 at 05-Nov-2012 23:21.
05-Nov 23:21 bacula-sd JobId 4130: 3307 Issuing autochanger "unload
slot 6, drive 0" command.
05-Nov 23:25 bacula-dir JobId 4130: There are no more Jobs associated
with Volume "DAY004L4". Marking it purged.
05-Nov 23:25 bacula-dir JobId 4130: All records pruned from Volume
"DAY004L4"; marking it "Purged"
05-Nov 23:25 bacula-dir JobId 4130: Recycled volume "DAY004L4"
05-Nov 23:25 bacula-sd JobId 4130: 3301 Issuing autochanger "loaded?
drive 0" command.
05-Nov 23:25 bacula-sd JobId 4130: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
05-Nov 23:25 bacula-sd JobId 4130: 3304 Issuing autochanger "load slot
7, drive 0" command.
05-Nov 23:26 bacula-sd JobId 4130: 3305 Autochanger "load slot 7,
drive 0", status is OK.
05-Nov 23:26 bacula-sd JobId 4130: Recycled volume "DAY004L4" on
device "HP-ULTRIUM" (/dev/nst0), all previous data lost.
05-Nov 23:26 bacula-sd JobId 4130: New volume "DAY004L4" mounted on
device "HP-ULTRIUM" (/dev/nst0) at 05-Nov-2012 23:26.
05-Nov 23:30 bacula-sd JobId 4130: Error: block.c:577 Write error at
0:14383 on device "HP-ULTRIUM" (/dev/nst0). ERR=Input/output error.
05-Nov 23:38 bacula-sd JobId 4130: Error: Error writing final EOF to
tape. This Volume may not be readable.
dev.c:1745 ioctl MTWEOF error on "HP-ULTRIUM" (/dev/nst0).
ERR=Input/output error.
05-Nov 23:38 bacula-sd JobId 4130: End of medium on Volume "DAY004L4"
Bytes=927,876,096 Blocks=14,382 at 05-Nov-2012 23:38.
05-Nov 23:38 bacula-sd JobId 4130: 3307 Issuing autochanger "unload
slot 7, drive 0" command.
05-Nov 23:39 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 00:39 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 02:40 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 06:36 bacula-dir JobId 4130: There are no more Jobs associated
with Volume "DAY005L4". Marking it purged.
06-Nov 06:36 bacula-dir JobId 4130: All records pruned from Volume
"DAY005L4"; marking it "Purged"
06-Nov 06:36 bacula-dir JobId 4130: Recycled volume "DAY005L4"
06-Nov 06:36 bacula-sd JobId 4130: 3301 Issuing autochanger "loaded?
drive 0" command.
06-Nov 06:36 bacula-sd JobId 4130: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
06-Nov 06:36 bacula-sd JobId 4130: 3304 Issuing autochanger "load slot
8, drive 0" command.
06-Nov 06:37 bacula-sd JobId 4130: 3305 Autochanger "load slot 8,
drive 0", status is OK.
06-Nov 06:37 bacula-sd JobId 4130: Recycled volume "DAY005L4" on
device "HP-ULTRIUM" (/dev/nst0), all previous data lost.
06-Nov 06:37 bacula-sd JobId 4130: New volume "DAY005L4" mounted on
device "HP-ULTRIUM" (/dev/nst0) at 06-Nov-2012 06:37.
06-Nov 06:42 bacula-sd JobId 4130: Error: block.c:577 Write error at
2:2136 on device "HP-ULTRIUM" (/dev/nst0). ERR=Input/output error.
06-Nov 06:57 bacula-sd JobId 4130: Error: Error writing final EOF to
tape. This Volume may not be readable.
dev.c:1745 ioctl MTWEOF error on "HP-ULTRIUM" (/dev/nst0).
ERR=Input/output error.
06-Nov 06:57 bacula-sd JobId 4130: End of medium on Volume "DAY005L4"
Bytes=2,137,669,632 Blocks=33,135 at 06-Nov-2012 06:57.
06-Nov 06:57 bacula-sd JobId 4130: 3307 Issuing autochanger "unload
slot 8, drive 0" command.
06-Nov 07:00 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 08:00 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 10:00 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
06-Nov 10:15 bacula-sd JobId 4130: 3301 Issuing autochanger "loaded?
drive 0" command.
06-Nov 10:15 bacula-sd JobId 4130: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
06-Nov 10:15 bacula-sd JobId 4130: 3304 Issuing autochanger "load slot
4, drive 0" command.
06-Nov 10:15 bacula-sd JobId 4130: 3305 Autochanger "load slot 4,
drive 0", status is OK.
06-Nov 10:16 bacula-sd JobId 4130: Recycled volume "DAY001L4" on
device "HP-ULTRIUM" (/dev/nst0), all previous data lost.
06-Nov 10:16 bacula-dir JobId 4130: Max configured use duration
exceeded. Marking Volume "DAY001L4" as Used.
06-Nov 10:16 bacula-sd JobId 4130: New volume "DAY001L4" mounted on
device "HP-ULTRIUM" (/dev/nst0) at 06-Nov-2012 10:16.
06-Nov 10:49 bacula-sd JobId 4130: Error: block.c:577 Write error at
36:4459 on device "HP-ULTRIUM" (/dev/nst0). ERR=Input/output error.
06-Nov 10:52 bacula-sd JobId 4130: Error: Error writing final EOF to
tape. This Volume may not be readable.
dev.c:1745 ioctl MTWEOF error on "HP-ULTRIUM" (/dev/nst0).
ERR=Input/output error.
06-Nov 10:52 bacula-sd JobId 4130: End of medium on Volume "DAY001L4"
Bytes=36,285,355,008 Blocks=562,458 at 06-Nov-2012 10:52.
06-Nov 10:52 bacula-sd JobId 4130: 3307 Issuing autochanger "unload
slot 4, drive 0" command.
06-Nov 10:53 bacula-sd JobId 4130: Job RSYNC.2012-11-05_23.00.00_10 is
waiting. Cannot find any appendable volumes.
Please use the "label" command to create a new Volume for:
    Storage:      "HP-ULTRIUM" (/dev/nst0)
    Pool:         Daily
    Media type:   LTO-4
===============================================================================================================
Questions are:


*   This means that I've 4 volumes damaged? I tried this night with a
new volume and the backup finished correctly.

*  why bacula doesn't sent me a mail with that error?


Messages {
  Name = Daemon
  mailcommand = "/usr/lib/bacula/bsmtp -h mail.mydomain -f
\"\(Bacula\) \<%r\>\" -s \"Messaggio dal servizio BACULA\" %r"
  mail = myemail@mydomain = all, !skipped
  console = all, !skipped, !saved
  append = "/var/lib/bacula/bacula.log" = all, !skipped
}

other emails arrives correctly.

versions:

dpkg -l | grep bacula
ii  bacula-client                       5.0.2-2.2+squeeze1
network backup, recovery and verification - client meta-package
ii  bacula-common                       5.0.2-2.2+squeeze1
network backup, recovery and verification - common support files
ii  bacula-common-mysql                 5.0.2-2.2+squeeze1
network backup, recovery and verification - MySQL common files
ii  bacula-console                      5.0.2-2.2+squeeze1
network backup, recovery and verification - text console
ii  bacula-console-qt                   5.0.2-2.2+squeeze1
Bacula Administration Tool Console
ii  bacula-director-common              5.0.2-2.2+squeeze1
network backup, recovery and verification - Director common files
ii  bacula-director-mysql               5.0.2-2.2+squeeze1
network backup, recovery and verification - MySQL storage for Director
ii  bacula-fd                           5.0.2-2.2+squeeze1
network backup, recovery and verification - file daemon
ii  bacula-sd                           5.0.2-2.2+squeeze1
network backup, recovery and verification - storage daemon
ii  bacula-sd-mysql                     5.0.2-2.2+squeeze1
network backup, recovery and verification - MySQL SD tools
ii  bacula-traymonitor                  5.0.2-2.2+squeeze1
network backup, recovery and verification - tray monitor


tanx
Mauro

------------------------------------------------------------------------------
LogMeIn Central: Instant, anywhere, Remote PC access and management.
Stay in control, update software, and manage PCs from one command center
Diagnose problems and improve visibility into emerging IT issues
Automate, monitor and manage. Do more in less time with Central
http://p.sf.net/sfu/logmein12331_d2d
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users

<Prev in Thread] Current Thread [Next in Thread>