[prev in list] [next in list] [prev in thread] [next in thread]
List: bacula-users
Subject: [Bacula-users] Problems with Max vol Jobs & Recycled Volume
From: Jérémy_Chaumont <jeremy.chaumont () sis-ca ! fr>
Date: 2009-04-30 8:31:25
Message-ID: !&!AAAAAAAAAAAYAAAAAAAAACoyJC42K1BMobpDz2c+OODCgAAAEAAAAHZZqDlJlbdBhBwYbYBNxSMBAAAAAA== () sis-ca ! fr
[Download RAW message or body]
[Attachment #2 (multipart/alternative)]
Hi all,
Since 2 weeks, I encountered some problems with bacula.
When I arrived in the company, bacula was configured for 4 jobs, with Max
Vol Jobs at 5. No problem, all saves were going perfectly.
First job :
13-Apr 23:05 SISCABK01-dir JobId 396: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
13-Apr 23:05 SISCABK01-dir JobId 396: Start Backup JobId 396,
Job=SISCABKO1-J01.2009-04-13_23.05.00.38
13-Apr 23:05 SISCABK01-dir JobId 396: Recycled volume "000009L3"
13-Apr 23:05 SISCABK01-dir JobId 396: Using Device "Drive-1"
13-Apr 23:05 SISCABK01-sd JobId 396: 3301 Issuing autochanger "loaded? drive
0" command.
13-Apr 23:05 SISCABK01-sd JobId 396: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
13-Apr 23:05 SISCABK01-sd JobId 396: 3304 Issuing autochanger "load slot 2,
drive 0" command.
13-Apr 23:05 SISCABK01-sd JobId 396: 3305 Autochanger "load slot 2, drive
0", status is OK.
13-Apr 23:05 SISCABK01-sd JobId 396: Recycled volume "000009L3" on device
"Drive-1" (/dev/nst0), all previous data lost.
13-Apr 23:05 SISCABK01-sd JobId 396: Job write elapsed time = 00:00:10,
Transfer rate = 79.54 M bytes/second 13-Apr 23:06 SISCABK01-dir JobId 396:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 13-Apr-2009 23:06:01
Last job :
13-Apr 23:10 SISCABK01-dir JobId 399: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
13-Apr 23:10 SISCABK01-dir JobId 399: Start Backup JobId 399,
Job=SISCAPSO1-J01.2009-04-13_23.05.00.41
13-Apr 23:10 SISCABK01-dir JobId 399: Using Device "Drive-1"
13-Apr 23:10 SISCABK01-sd JobId 399: 3301 Issuing autochanger "loaded? drive
0" command.
13-Apr 23:10 SISCABK01-sd JobId 399: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
13-Apr 23:10 SISCABK01-sd JobId 399: 3304 Issuing autochanger "load slot 2,
drive 0" command.
13-Apr 23:10 SISCABK01-sd JobId 399: 3305 Autochanger "load slot 2, drive
0", status is OK.
13-Apr 23:10 SISCABK01-sd JobId 399: Volume "000009L3" previously written,
moving to end of data.
13-Apr 23:10 SISCABK01-sd JobId 399: Ready to append to end of Volume
"000009L3" at file=3.
13-Apr 23:10 SISCABK01-sd JobId 399: Job write elapsed time = 00:00:02,
Transfer rate = 1.785 M bytes/second 13-Apr 23:10 SISCABK01-dir JobId 399:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 13-Apr-2009 23:10:55
I have to add 2 new jobs, so I put the Max Vol Jobs at 7 and for a week, no
problem.
First job :
15-Apr 23:05 SISCABK01-dir JobId 409: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
15-Apr 23:05 SISCABK01-dir JobId 409: Start Backup JobId 409,
Job=SISCABKO1-J01.2009-04-15_23.05.00.45
15-Apr 23:05 SISCABK01-dir JobId 409: There are no more Jobs associated with
Volume "000011L3". Marking it purged.
15-Apr 23:05 SISCABK01-dir JobId 409: All records pruned from Volume
"000011L3"; marking it "Purged"
15-Apr 23:05 SISCABK01-dir JobId 409: Recycled volume "000011L3"
15-Apr 23:05 SISCABK01-dir JobId 409: Using Device "Drive-1"
15-Apr 23:05 SISCABK01-sd JobId 409: 3301 Issuing autochanger "loaded? drive
0" command.
15-Apr 23:05 SISCABK01-sd JobId 409: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
15-Apr 23:05 SISCABK01-sd JobId 409: 3304 Issuing autochanger "load slot 4,
drive 0" command.
15-Apr 23:05 SISCABK01-sd JobId 409: 3305 Autochanger "load slot 4, drive
0", status is OK.
15-Apr 23:05 SISCABK01-sd JobId 409: Recycled volume "000011L3" on device
"Drive-1" (/dev/nst0), all previous data lost.
15-Apr 23:06 SISCABK01-sd JobId 409: Job write elapsed time = 00:00:10,
Transfer rate = 82.73 M bytes/second 15-Apr 23:06 SISCABK01-dir JobId 409:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 15-Apr-2009 23:06:06
Last Job:
15-Apr 23:15 SISCABK01-dir JobId 414: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
15-Apr 23:16 SISCABK01-dir JobId 414: Start Backup JobId 414,
Job=SISCAASB01-J01.2009-04-15_23.05.00.50
15-Apr 23:16 SISCABK01-dir JobId 414: Using Device "Drive-1"
15-Apr 23:16 SISCABK01-fd JobId 414: DIR and FD clocks differ by 44 seconds,
FD automatically compensating.
15-Apr 23:16 SISCABK01-sd JobId 414: 3301 Issuing autochanger "loaded? drive
0" command.
15-Apr 23:16 SISCABK01-sd JobId 414: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
15-Apr 23:16 SISCABK01-sd JobId 414: 3304 Issuing autochanger "load slot 4,
drive 0" command.
15-Apr 23:16 SISCABK01-sd JobId 414: 3305 Autochanger "load slot 4, drive
0", status is OK.
15-Apr 23:16 SISCABK01-sd JobId 414: Volume "000011L3" previously written,
moving to end of data.
15-Apr 23:17 SISCABK01-sd JobId 414: Ready to append to end of Volume
"000011L3" at file=8.
15-Apr 23:19 SISCABK01-sd JobId 414: Job write elapsed time = 00:02:18,
Transfer rate = 9.417 M bytes/second 15-Apr 23:19 SISCABK01-dir JobId 414:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 15-Apr-2009 23:19:35
But one week ago, bacula started to send me errors. Only the first 3 jobs
succeeded.
First Job :
20-Apr 23:05 SISCABK01-dir JobId 439: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
20-Apr 23:05 SISCABK01-dir JobId 439: Start Backup JobId 439,
Job=SISCABKO1-J01.2009-04-20_23.05.00.00
20-Apr 23:05 SISCABK01-dir JobId 439: Using Device "Drive-1"
20-Apr 23:05 SISCABK01-sd JobId 439: 3301 Issuing autochanger "loaded? drive
0" command.
20-Apr 23:05 SISCABK01-sd JobId 439: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
20-Apr 23:05 SISCABK01-sd JobId 439: 3304 Issuing autochanger "load slot 2,
drive 0" command.
20-Apr 23:05 SISCABK01-sd JobId 439: 3305 Autochanger "load slot 2, drive
0", status is OK.
20-Apr 23:05 SISCABK01-sd JobId 439: Volume "000016L3" previously written,
moving to end of data.
20-Apr 23:05 SISCABK01-sd JobId 439: Ready to append to end of Volume
"000016L3" at file=4.
20-Apr 23:06 SISCABK01-sd JobId 439: Job write elapsed time = 00:00:12,
Transfer rate = 75.53 M bytes/second 20-Apr 23:06 SISCABK01-dir JobId 439:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 20-Apr-2009 23:06:11
Last one successful :
20-Apr 23:08 SISCABK01-dir JobId 441: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
20-Apr 23:09 SISCABK01-dir JobId 441: Start Backup JobId 441,
Job=SISCAFTO1-J01.2009-04-20_23.05.00.02
20-Apr 23:09 SISCABK01-dir JobId 441: Using Device "Drive-1"
20-Apr 23:09 SISCABK01-sd JobId 441: 3301 Issuing autochanger "loaded? drive
0" command.
20-Apr 23:09 SISCABK01-sd JobId 441: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
20-Apr 23:09 SISCABK01-sd JobId 441: 3304 Issuing autochanger "load slot 2,
drive 0" command.
20-Apr 23:09 SISCABK01-sd JobId 441: 3305 Autochanger "load slot 2, drive
0", status is OK.
20-Apr 23:09 SISCABK01-sd JobId 441: Volume "000016L3" previously written,
moving to end of data.
20-Apr 23:09 SISCABK01-sd JobId 441: Ready to append to end of Volume
"000016L3" at file=6.
20-Apr 23:09 SISCABK01-dir JobId 441: Max Volume jobs exceeded. Marking
Volume "000016L3" as Used.
20-Apr 23:10 SISCABK01-sd JobId 441: Job write elapsed time = 00:00:20,
Transfer rate = 11.05 M bytes/second 20-Apr 23:10 SISCABK01-dir JobId 441:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 20-Apr-2009 23:10:10
Error :
20-Apr 23:11 SISCABK01-sd JobId 442: Please mount Volume "000009L3" or label
a new one for:
Job: SISCAASO1-J01.2009-04-20_23.05.00.03
Storage: "Drive-1" (/dev/nst0)
Pool: MondayPool
Media type: LTO3
I update all pools, volume etc., looked at all configuration files but
nothing. So one day I updated the Max Vol Jobs to 10 and all jobs were
successful.
Last :
24-avr 23:13 SISCABK01-dir JobId 466: BeforeJob: run command
"/opt/bacula/etc/make_catalog_backup bacula bacula"
24-avr 23:13 SISCABK01-dir JobId 466: Start Backup JobId 466,
Job=SISCAASB01-J01.2009-04-24_23.05.00.20
24-avr 23:13 SISCABK01-dir JobId 466: Using Device "Drive-1"
24-avr 23:14 SISCABK01-fd JobId 466: DIR and FD clocks differ by 66 seconds,
FD automatically compensating.
24-avr 23:13 SISCABK01-sd JobId 466: 3301 Issuing autochanger "loaded? drive
0" command.
24-avr 23:13 SISCABK01-sd JobId 466: 3302 Autochanger "loaded? drive 0",
result: nothing loaded.
24-avr 23:13 SISCABK01-sd JobId 466: 3304 Issuing autochanger "load slot 6,
drive 0" command.
24-avr 23:14 SISCABK01-sd JobId 466: 3305 Autochanger "load slot 6, drive
0", status is OK.
24-avr 23:14 SISCABK01-sd JobId 466: Volume "000020L3" previously written,
moving to end of data.
24-avr 23:14 SISCABK01-sd JobId 466: Ready to append to end of Volume
"000020L3" at file=7.
24-avr 23:15 SISCABK01-sd JobId 466: Job write elapsed time = 00:00:20,
Transfer rate = 5.058 M bytes/second 24-avr 23:15 SISCABK01-dir JobId 466:
Bacula SISCABK01-dir 2.4.4 (28Dec08): 24-avr-2009 23:15:03
And this morning when I check the logs and I can see an error. 6 jobs with
success, and not the last one.
Same problem than one week ago before update Max Vol jobs to 10.
And after reading all logs from 3 weeks, I can tell (I think) where the
problem is, but not how to solve it.
If we look at the logs, the first job have not the line :
Recycled volume "00000***" on device "Drive-1" (/dev/nst0), all previous
data lost
I think this problem come from the autoprune option, but I haven’t make any
change on it !!
I check all the volume, from 1 to 15, I have the recycling, but since 16,
no. I haven’t make any changes between 15 and 16. I don’t understand.
So, please help me !! ^^
Thanks all
[Attachment #5 (text/html)]
<html xmlns:v="urn:schemas-microsoft-com:vml" \
xmlns:o="urn:schemas-microsoft-com:office:office" \
xmlns:w="urn:schemas-microsoft-com:office:word" \
xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" \
xmlns="http://www.w3.org/TR/REC-html40">
<head>
<meta http-equiv=Content-Type content="text/html; charset=iso-8859-1">
<meta name=Generator content="Microsoft Word 12 (filtered medium)">
<style>
<!--
/* Font Definitions */
@font-face
{font-family:"Cambria Math";
panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
{font-family:Consolas;
panose-1:2 11 6 9 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri","sans-serif";}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:blue;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:purple;
text-decoration:underline;}
p.MsoPlainText, li.MsoPlainText, div.MsoPlainText
{mso-style-priority:99;
mso-style-link:"Texte brut Car";
margin:0cm;
margin-bottom:.0001pt;
font-size:10.5pt;
font-family:Consolas;}
span.EmailStyle17
{mso-style-type:personal-compose;
font-family:"Calibri","sans-serif";
color:windowtext;}
span.TextebrutCar
{mso-style-name:"Texte brut Car";
mso-style-priority:99;
mso-style-link:"Texte brut";
font-family:Consolas;}
.MsoChpDefault
{mso-style-type:export-only;}
@page Section1
{size:612.0pt 792.0pt;
margin:70.85pt 70.85pt 70.85pt 70.85pt;}
div.Section1
{page:Section1;}
-->
</style>
<!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]-->
</head>
<body lang=FR link=blue vlink=purple>
<div class=Section1>
<p class=MsoNormal>Hi all,<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal><span lang=EN-US>Since 2 weeks, I encountered some problems
with bacula.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US>When I arrived in the company, bacula was \
configured for 4 jobs, with Max Vol Jobs at 5. No problem, all saves were going \
perfectly.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>First job :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-dir JobId 396:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula \
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-dir JobId 396:
Start Backup JobId 396, \
Job=SISCABKO1-J01.2009-04-13_23.05.00.38<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-dir JobId 396:
Recycled volume "000009L3"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-dir JobId 396:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396:
3304 Issuing autochanger "load slot 2, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396:
3305 Autochanger "load slot 2, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396:
Recycled volume "000009L3" on device "Drive-1" (/dev/nst0),
all previous data lost.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:05 SISCABK01-sd JobId 396: Job
write elapsed time = 00:00:10, Transfer rate = 79.54 M bytes/second 13-Apr
23:06 SISCABK01-dir JobId 396: Bacula SISCABK01-dir 2.4.4 (28Dec08):
13-Apr-2009 23:06:01<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Last job :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-dir JobId 399:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-dir JobId 399:
Start Backup JobId 399, \
Job=SISCAPSO1-J01.2009-04-13_23.05.00.41<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-dir JobId 399:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
3304 Issuing autochanger "load slot 2, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
3305 Autochanger "load slot 2, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
Volume "000009L3" previously written, moving to end of \
data.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399:
Ready to append to end of Volume "000009L3" at \
file=3.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US>13-Apr 23:10 SISCABK01-sd JobId 399: Job
write elapsed time = 00:00:02, Transfer rate = 1.785 M bytes/second 13-Apr
23:10 SISCABK01-dir JobId 399: Bacula SISCABK01-dir 2.4.4 (28Dec08):
13-Apr-2009 23:10:55<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>I have to add 2 new jobs, so I put the Max
Vol Jobs at 7 and for a week, no problem.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>First job :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
Start Backup JobId 409, \
Job=SISCABKO1-J01.2009-04-15_23.05.00.45<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
There are no more Jobs associated with Volume "000011L3". Marking it
purged.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
All records pruned from Volume "000011L3"; marking it
"Purged"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
Recycled volume "000011L3"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-dir JobId 409:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-sd JobId 409:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-sd JobId 409:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-sd JobId 409:
3304 Issuing autochanger "load slot 4, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-sd JobId 409:
3305 Autochanger "load slot 4, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:05 SISCABK01-sd JobId 409:
Recycled volume "000011L3" on device "Drive-1" (/dev/nst0),
all previous data lost.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:06 SISCABK01-sd JobId 409: Job
write elapsed time = 00:00:10, Transfer rate = 82.73 M bytes/second 15-Apr
23:06 SISCABK01-dir JobId 409: Bacula SISCABK01-dir 2.4.4 (28Dec08):
15-Apr-2009 23:06:06<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Last Job:<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:15 SISCABK01-dir JobId 414:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-dir JobId 414:
Start Backup JobId 414, \
Job=SISCAASB01-J01.2009-04-15_23.05.00.50<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-dir JobId 414:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-fd JobId 414: DIR
and FD clocks differ by 44 seconds, FD automatically \
compensating.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-sd JobId 414:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-sd JobId 414:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-sd JobId 414:
3304 Issuing autochanger "load slot 4, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-sd JobId 414:
3305 Autochanger "load slot 4, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:16 SISCABK01-sd JobId 414:
Volume "000011L3" previously written, moving to end of \
data.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:17 SISCABK01-sd JobId 414:
Ready to append to end of Volume "000011L3" at \
file=8.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>15-Apr 23:19 SISCABK01-sd JobId 414: Job
write elapsed time = 00:02:18, Transfer rate = 9.417 M bytes/second 15-Apr
23:19 SISCABK01-dir JobId 414: Bacula SISCABK01-dir 2.4.4 (28Dec08):
15-Apr-2009 23:19:35<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>But one week ago, bacula started to send me
errors. Only the first 3 jobs succeeded.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>First Job :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-dir JobId 439:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-dir JobId 439:
Start Backup JobId 439, \
Job=SISCABKO1-J01.2009-04-20_23.05.00.00<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-dir JobId 439: Using
Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
3304 Issuing autochanger "load slot 2, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
3305 Autochanger "load slot 2, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
Volume "000016L3" previously written, moving to end of \
data.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:05 SISCABK01-sd JobId 439:
Ready to append to end of Volume "000016L3" at \
file=4.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:06 SISCABK01-sd JobId 439: Job
write elapsed time = 00:00:12, Transfer rate = 75.53 M bytes/second 20-Apr
23:06 SISCABK01-dir JobId 439: Bacula SISCABK01-dir 2.4.4 (28Dec08): 20-Apr-2009
23:06:11<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Last one successful :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:08 SISCABK01-dir JobId 441:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-dir JobId 441:
Start Backup JobId 441, \
Job=SISCAFTO1-J01.2009-04-20_23.05.00.02<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-dir JobId 441:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
3304 Issuing autochanger "load slot 2, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
3305 Autochanger "load slot 2, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
Volume "000016L3" previously written, moving to end of \
data.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-sd JobId 441:
Ready to append to end of Volume "000016L3" at \
file=6.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:09 SISCABK01-dir JobId 441:
Max Volume jobs exceeded. Marking Volume "000016L3" as \
Used.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:10 SISCABK01-sd JobId 441: Job
write elapsed time = 00:00:20, Transfer rate = 11.05 M bytes/second 20-Apr
23:10 SISCABK01-dir JobId 441: Bacula SISCABK01-dir 2.4.4 (28Dec08):
20-Apr-2009 23:10:10<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Error :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>20-Apr 23:11 SISCABK01-sd JobId 442:
Please mount Volume "000009L3" or label a new one \
for:<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US> Job:
SISCAASO1-J01.2009-04-20_23.05.00.03<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US> Storage: "Drive-1"
(/dev/nst0)<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US> </span>Pool: \
MondayPool<o:p></o:p></p>
<p class=MsoNormal> Media type: LTO3<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal><span lang=EN-US>I update all pools, volume etc., looked at all
configuration files but nothing. So one day I updated the Max Vol Jobs to 10
and all jobs were successful.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Last :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-dir JobId 466:
BeforeJob: run command "/opt/bacula/etc/make_catalog_backup bacula
bacula"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-dir JobId 466:
Start Backup JobId 466, \
Job=SISCAASB01-J01.2009-04-24_23.05.00.20<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-dir JobId 466:
Using Device "Drive-1"<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:14 SISCABK01-fd JobId 466: DIR
and FD clocks differ by 66 seconds, FD automatically \
compensating.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-sd JobId 466:
3301 Issuing autochanger "loaded? drive 0" command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-sd JobId 466:
3302 Autochanger "loaded? drive 0", result: nothing \
loaded.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:13 SISCABK01-sd JobId 466:
3304 Issuing autochanger "load slot 6, drive 0" \
command.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:14 SISCABK01-sd JobId 466:
3305 Autochanger "load slot 6, drive 0", status is \
OK.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:14 SISCABK01-sd JobId 466:
Volume "000020L3" previously written, moving to end of \
data.<o:p></o:p></span></p>
<p class=MsoPlainText><span lang=EN-US>24-avr 23:14 SISCABK01-sd JobId 466:
Ready to append to end of Volume "000020L3" at \
file=7.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US>24-avr 23:15 SISCABK01-sd JobId 466: Job
write elapsed time = 00:00:20, Transfer rate = 5.058 M bytes/second 24-avr
23:15 SISCABK01-dir JobId 466: Bacula SISCABK01-dir 2.4.4 (28Dec08): 24-avr-2009
23:15:03<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>And this morning when I check the logs and I
can see an error. 6 jobs with success, and not the last one.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Same problem than one week ago before
update Max Vol jobs to 10.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>And after reading all logs from 3 weeks, I can
tell (I think) where the problem is, but not how to solve it.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US>If we look at the logs, the first job have
not the line :<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Recycled volume "00000***" on
device "Drive-1" (/dev/nst0), all previous data lost<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>I think this problem come from the autoprune
option, but I haven’t make any change on it !!<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>I check all the volume, from 1 to 15, I have
the recycling, but since 16, no. I haven’t make any changes between 15
and 16. I don’t understand.<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>So, please help me !! ^^<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US>Thanks all<o:p></o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
<p class=MsoNormal><span lang=EN-US><o:p> </o:p></span></p>
</div>
</body>
</html>
["bacula-dir.conf" (application/octet-stream)]
------------------------------------------------------------------------------
Register Now & Save for Velocity, the Web Performance & Operations
Conference from O'Reilly Media. Velocity features a full day of
expert-led, hands-on workshops and two days of sessions from industry
leaders in dedicated Performance & Operations tracks. Use code vel09scf
and Save an extra 15% before 5/3. http://p.sf.net/sfu/velocityconf
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
[prev in list] [next in list] [prev in thread] [next in thread]
Configure |
About |
News |
Add a list |
Sponsored by KoreLogic