Re: [Bacula-users] Job started twice

2007-10-24 Thread Michael Short
> ... and it looks completely sane to me. No fancy schedules, no run=
> directives, nothing uncommon in it. But what is in the secnet-def
> JobDefauls resource?

I won't be able to run the test until later, this is my JobDefault for
reference:

JobDefs {
  Name = "secnet-def"
  Type = Backup
  Level = Full
  FileSet = "secnet-def"
  Storage = "File"
  Messages = "Standard"
  Priority = 10
}

The FileSet "secnet-def" is overridden by the the client
configuration. Tonite I will leave the director in debugging mode for
the night and see if the jobs are interacting. I scheduled the backup
to run on its lonesome but it seemed to work fine.


Sincerely,
-Michael

-
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
___
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users


Re: [Bacula-users] Job started twice

2007-10-24 Thread Arno Lehmann
Hi,

24.10.2007 15:06,, Michael Short wrote::
> Here is the configuration for the client, it is the same as every other.

... and it looks completely sane to me. No fancy schedules, no run= 
directives, nothing uncommon in it. But what is in the secnet-def 
JobDefauls resource?

Other than that, I can't see anything here.

Probably time to run with debug output...

Arno

> #DIRECTOR
> Client {
>   Name = "sv27"
>   Address = 10.123.0.25
>   FDPort = 1
>   Catalog = "MyCatalog"
>   Password = ""
>   AutoPrune = no
> }
> Job {
>   Name = "sv27"
>   Client = "sv27"
>   JobDefs = "secnet-def"
>   Write Bootstrap = "/home/bacula/sv27.bsr"
>   Schedule = "sv27"
>   Storage = "sv27"
>   Pool = "sv27"
>   Fileset = "sv27"
>   Enabled = "yes"
> }
> Pool {
>   Name = "sv27"
>   Pool Type = Backup
>   Recycle = no
>   AutoPrune = no
>   LabelFormat = "sv27"
>   UseVolumeOnce = yes
> }
> Storage {
>   Name = "sv27"
>   Address = 10.123.0.1
>   SDPort = 10001
>   Password = ""
>   Media Type = File
>   Device = "sv27"
> }
> Storage {
>   Name = "sv27-onsite"
>   Address = 10.123.0.25
>   SDPort = 10001
>   Password = ""
>   Media Type = File
>   Device = "sv27"
> }
> FileSet {
>   Name = "sv27"
>   Ignore FileSet Changes = yes
>   Enable VSS = yes
>   Include {
> Options {
>   signature = MD5
>   compression = GZIP
>   sparse = yes
> }
> File = "c:/"
> File = "d:/"
>   }
> }
> Schedule {
>   Name = "sv27"
>   Run = Level=Incremental sun-sat at 18:00
> }
> 
> 
> #STORAGE DAEMON
> Device {
>   Name = "sv27"
>   Media Type = File
>   Archive Device = "/home/bacula/storage/d1"
>   LabelMedia = yes; # Automatically label new volumes
>   Random Access = Yes; # Filesystem environment
>   AutomaticMount = yes; # Filesystem is always available
>   RemovableMedia = no; # A filesystem is NOT removable
>   AlwaysOpen = no; # Not important for filesystem usage
> }
> 
> -
> This SF.net email is sponsored by: Splunk Inc.
> Still grepping through log files to find problems?  Stop.
> Now Search log events and configuration files using AJAX and a browser.
> Download your FREE copy of Splunk now >> http://get.splunk.com/
> ___
> Bacula-users mailing list
> Bacula-users@lists.sourceforge.net
> https://lists.sourceforge.net/lists/listinfo/bacula-users
> 

-- 
Arno Lehmann
IT-Service Lehmann
www.its-lehmann.de

-
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
___
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users


Re: [Bacula-users] Job started twice

2007-10-24 Thread Michael Short
Here is the configuration for the client, it is the same as every other.

#DIRECTOR
Client {
  Name = "sv27"
  Address = 10.123.0.25
  FDPort = 1
  Catalog = "MyCatalog"
  Password = ""
  AutoPrune = no
}
Job {
  Name = "sv27"
  Client = "sv27"
  JobDefs = "secnet-def"
  Write Bootstrap = "/home/bacula/sv27.bsr"
  Schedule = "sv27"
  Storage = "sv27"
  Pool = "sv27"
  Fileset = "sv27"
  Enabled = "yes"
}
Pool {
  Name = "sv27"
  Pool Type = Backup
  Recycle = no
  AutoPrune = no
  LabelFormat = "sv27"
  UseVolumeOnce = yes
}
Storage {
  Name = "sv27"
  Address = 10.123.0.1
  SDPort = 10001
  Password = ""
  Media Type = File
  Device = "sv27"
}
Storage {
  Name = "sv27-onsite"
  Address = 10.123.0.25
  SDPort = 10001
  Password = ""
  Media Type = File
  Device = "sv27"
}
FileSet {
  Name = "sv27"
  Ignore FileSet Changes = yes
  Enable VSS = yes
  Include {
Options {
  signature = MD5
  compression = GZIP
  sparse = yes
}
File = "c:/"
File = "d:/"
  }
}
Schedule {
  Name = "sv27"
  Run = Level=Incremental sun-sat at 18:00
}


#STORAGE DAEMON
Device {
  Name = "sv27"
  Media Type = File
  Archive Device = "/home/bacula/storage/d1"
  LabelMedia = yes; # Automatically label new volumes
  Random Access = Yes; # Filesystem environment
  AutomaticMount = yes; # Filesystem is always available
  RemovableMedia = no; # A filesystem is NOT removable
  AlwaysOpen = no; # Not important for filesystem usage
}

-
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
___
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users


Re: [Bacula-users] Job started twice

2007-10-23 Thread Arno Lehmann
Hello,

23.10.2007 23:42,, Michael Short wrote::
>> I assume it's always the same job that behaves like this?
> 
> Yeah.
> 
>> It might be started twice because the DIR clock jumps.
> 
> That could very well be the problem, seems theres some 30 minutes
> difference between the clocks.
> 
>> So that would be for the second job started.
> 
> Yeah thats what I'm thinking.
> 
>> Have you checked what happens when you run this job manually, when no
>> other jobs are active?
> 
> I plan to try this once I figure out how to get the schedules updated.
> 
>> Before you report this as a bug it would be good to supply some more
>> information - the versions of Bacula, for example :-)
> 
> Running bacula-dir and bacula-sd 2.0.3, and bacula-fd 2.0.0. I know
> there are some serious bugs that have been fixed and I am working on
> upgrading (not an easy task).

Ok... 2.0.3 doesn't have any scheduling problems I know of.

>> What I'd do in your situation is first making sure the problem happens
>> also when only this one job is active, i.e. disable all your other
>> jobs, or schedule this problem job differently.
>>
>> Then, run the DIR with debug tracing enabled and see if that tells you
>> something.
>>
>> All this is worth the effort only with a reasonably recent version of
>> Bacula as I suppose Kern would not spend much time on 1.38 bugs anymore...
> 
> I ran the backup manually with run and there seemed to be no problems.

Ok, so for now we'll concentrate on the schedules.

Can you post the schedules referenced in your job definition?

> Also, when I set a delay in the run time there aren't any problems.
> However, I'm not sure how to update the scheduled jobs list after it
> has already been generated... I updated the configuration but it
> doesn't seem to care. Also tried deleting bacula's state file but that
> didn't work either. Any tips?

The state file doesn't matter here... did you do a reload after the 
configuration change? That should lead to the jobs being freshly 
scheduled.

Arno

> 
> Sincerely,
> -Michael
> 

-- 
Arno Lehmann
IT-Service Lehmann
www.its-lehmann.de

-
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
___
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users


Re: [Bacula-users] Job started twice

2007-10-23 Thread Michael Short
> I assume it's always the same job that behaves like this?

Yeah.

> It might be started twice because the DIR clock jumps.

That could very well be the problem, seems theres some 30 minutes
difference between the clocks.

> So that would be for the second job started.

Yeah thats what I'm thinking.

> Have you checked what happens when you run this job manually, when no
> other jobs are active?

I plan to try this once I figure out how to get the schedules updated.

> Before you report this as a bug it would be good to supply some more
> information - the versions of Bacula, for example :-)

Running bacula-dir and bacula-sd 2.0.3, and bacula-fd 2.0.0. I know
there are some serious bugs that have been fixed and I am working on
upgrading (not an easy task).

> What I'd do in your situation is first making sure the problem happens
> also when only this one job is active, i.e. disable all your other
> jobs, or schedule this problem job differently.
>
> Then, run the DIR with debug tracing enabled and see if that tells you
> something.
>
> All this is worth the effort only with a reasonably recent version of
> Bacula as I suppose Kern would not spend much time on 1.38 bugs anymore...

I ran the backup manually with run and there seemed to be no problems.
Also, when I set a delay in the run time there aren't any problems.
However, I'm not sure how to update the scheduled jobs list after it
has already been generated... I updated the configuration but it
doesn't seem to care. Also tried deleting bacula's state file but that
didn't work either. Any tips?

Sincerely,
-Michael

-
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
___
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users


Re: [Bacula-users] Job started twice

2007-10-23 Thread Arno Lehmann
Hi,

23.10.2007 22:11,, Michael Short wrote::
> Hello,
> 
> In my bacula configuration, I have a single client configuration (and
> job) for every one of my ~30 clients. However, recently, one of the
> jobs has started to act differently. When the backup starts, it starts
> again, only to cause the job to fail.

I assume it's always the same job that behaves like this?

> 21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
> 21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
> 21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
> automatically adjusting.

It might be started twice because the DIR clock jumps.

> 21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
> "sv27" (/home/bacula/storage/d1).
> 21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
> on device "sv27" (/home/bacula/storage/d1)
> 21-Oct 18:00 secnet-def: Volume used once. Marking Volume "sv270095" as Used.
> 21-Oct 17:42 sv27: Generate VSS snapshots. Driver="VSS Win 2003", 
> Drive(s)="CD"
> 21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
> 21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
> automatically adjusting.
> 21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
> "sv27" (/home/bacula/storage/d1).
> 21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
> on device "sv27" (/home/bacula/storage/d1)
> 21-Oct 18:00 secnet-def: Volume used once. Marking Volume "sv270095" as Used.
> 21-Oct 17:42 sv27: Generate VSS snapshots. Driver="VSS Win 2003", 
> Drive(s)="CD"
> 21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
> 21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
> automatically adjusting.
> 21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
> "sv27" (/home/bacula/storage/d1).
> 21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
> on device "sv27" (/home/bacula/storage/d1)

Definitely something funny going on here...

> 
>  this volume labeling sherade continues to happen about 10 more times 
> 
>   JobId:  4376
>   Job:sv27.2007-10-21_18.00.07
>   Backup Level:   Incremental, since=2007-10-08 18:00:21
>   Client: "sv27" 2.0.0 (04Jan07) Linux,Cross-compile,Win32
>   FileSet:"sv27" 2007-07-10 09:43:58
>   Pool:   "sv27" (From Job resource)
>   Storage:"sv27" (From Job resource)
>   Scheduled time: 21-Oct-2007 18:00:06
>   Start time: 21-Oct-2007 18:00:21
>   End time:   22-Oct-2007 18:01:02
>   Elapsed time:   1 day 41 secs
>   Priority:   10
>   FD Files Written:   0
>   SD Files Written:   0
>   FD Bytes Written:   0 (0 B)
>   SD Bytes Written:   0 (0 B)
>   Rate:   0.0 KB/s
>   Software Compression:   None
>   VSS:no
>   Encryption: no
>   Volume name(s): sv270095
>   Volume Session Id:  51
>   Volume Session Time:1192828139
>   Last Volume Bytes:  2,999,807,971 (2.999 GB)
>   Non-fatal FD errors:0
>   SD Errors:  0
>   FD termination status:  Error
>   SD termination status:  Error
>   Termination:*** Backup Error ***
> 
> However, I will then get a second error for the same job (but not the
> same JobId!).

So that would be for the second job started.

> 22-Oct 18:01 secnet-def: Start Backup JobId 4397, Job=sv27.2007-10-22_18.00.07
> 22-Oct 18:01 secnet-def: Start Backup JobId 4397, Job=sv27.2007-10-22_18.00.07
> 22-Oct 18:01 secnet-def: Created new Volume "sv270096" in catalog.
> 22-Oct 17:43 sv27: DIR and FD clocks differ by -1083 seconds, FD
> automatically adjusting.
> 22-Oct 18:01 secnet-def: sv27.2007-10-22_18.00.07 Fatal error:
> acquire.c:355 Wanted to append to Volume "sv270096", but device "sv27"
> (/home/bacula/storage/d1) is busy writing on "sv270095" .
> 22-Oct 17:43 sv27: sv27.2007-10-22_18.00.07 Fatal error:
> ../../filed/job.c:1751 Bad response to Append Data command. Wanted
> 3000 OK data
> , got 3903 Error append data
> 
> 22-Oct 18:01 secnet-def: sv27.2007-10-22_18.00.07 Error: Bacula 2.0.3
> (06Mar07): 22-Oct-2007 18:01:16
>   JobId:  4397
>   Job:sv27.2007-10-22_18.00.07
>   Backup Level:   Incremental, since=2007-10-08 18:00:21
>   Client: "sv27" 2.0.0 (04Jan07) Linux,Cross-compile,Win32
>   FileSet:"sv27" 2007-07-10 09:43:58
>   Pool:   "sv27" (From Job resource)
>   Storage:"sv27" (From Job resource)
>   Scheduled time: 22-Oct-2007 18:00:06
>   Start time: 22-Oct-2007 18:01:14
>   End time:   22-Oct-2007 18:01:16
>   Elapsed time:   2 secs
>   Priority:   10
>   FD Files Written:   0
>   SD Files Written:   0
>   FD Bytes Wr

[Bacula-users] Job started twice

2007-10-23 Thread Michael Short
Hello,

In my bacula configuration, I have a single client configuration (and
job) for every one of my ~30 clients. However, recently, one of the
jobs has started to act differently. When the backup starts, it starts
again, only to cause the job to fail.

21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
automatically adjusting.
21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
"sv27" (/home/bacula/storage/d1).
21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
on device "sv27" (/home/bacula/storage/d1)
21-Oct 18:00 secnet-def: Volume used once. Marking Volume "sv270095" as Used.
21-Oct 17:42 sv27: Generate VSS snapshots. Driver="VSS Win 2003", Drive(s)="CD"
21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
automatically adjusting.
21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
"sv27" (/home/bacula/storage/d1).
21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
on device "sv27" (/home/bacula/storage/d1)
21-Oct 18:00 secnet-def: Volume used once. Marking Volume "sv270095" as Used.
21-Oct 17:42 sv27: Generate VSS snapshots. Driver="VSS Win 2003", Drive(s)="CD"
21-Oct 18:00 secnet-def: Start Backup JobId 4376, Job=sv27.2007-10-21_18.00.07
21-Oct 17:42 sv27: DIR and FD clocks differ by -1078 seconds, FD
automatically adjusting.
21-Oct 18:00 secnet-def: Labeled new Volume "sv270095" on device
"sv27" (/home/bacula/storage/d1).
21-Oct 18:00 secnet-def: Wrote label to prelabeled Volume "sv270095"
on device "sv27" (/home/bacula/storage/d1)

 this volume labeling sherade continues to happen about 10 more times 

  JobId:  4376
  Job:sv27.2007-10-21_18.00.07
  Backup Level:   Incremental, since=2007-10-08 18:00:21
  Client: "sv27" 2.0.0 (04Jan07) Linux,Cross-compile,Win32
  FileSet:"sv27" 2007-07-10 09:43:58
  Pool:   "sv27" (From Job resource)
  Storage:"sv27" (From Job resource)
  Scheduled time: 21-Oct-2007 18:00:06
  Start time: 21-Oct-2007 18:00:21
  End time:   22-Oct-2007 18:01:02
  Elapsed time:   1 day 41 secs
  Priority:   10
  FD Files Written:   0
  SD Files Written:   0
  FD Bytes Written:   0 (0 B)
  SD Bytes Written:   0 (0 B)
  Rate:   0.0 KB/s
  Software Compression:   None
  VSS:no
  Encryption: no
  Volume name(s): sv270095
  Volume Session Id:  51
  Volume Session Time:1192828139
  Last Volume Bytes:  2,999,807,971 (2.999 GB)
  Non-fatal FD errors:0
  SD Errors:  0
  FD termination status:  Error
  SD termination status:  Error
  Termination:*** Backup Error ***

However, I will then get a second error for the same job (but not the
same JobId!).

22-Oct 18:01 secnet-def: Start Backup JobId 4397, Job=sv27.2007-10-22_18.00.07
22-Oct 18:01 secnet-def: Start Backup JobId 4397, Job=sv27.2007-10-22_18.00.07
22-Oct 18:01 secnet-def: Created new Volume "sv270096" in catalog.
22-Oct 17:43 sv27: DIR and FD clocks differ by -1083 seconds, FD
automatically adjusting.
22-Oct 18:01 secnet-def: sv27.2007-10-22_18.00.07 Fatal error:
acquire.c:355 Wanted to append to Volume "sv270096", but device "sv27"
(/home/bacula/storage/d1) is busy writing on "sv270095" .
22-Oct 17:43 sv27: sv27.2007-10-22_18.00.07 Fatal error:
../../filed/job.c:1751 Bad response to Append Data command. Wanted
3000 OK data
, got 3903 Error append data

22-Oct 18:01 secnet-def: sv27.2007-10-22_18.00.07 Error: Bacula 2.0.3
(06Mar07): 22-Oct-2007 18:01:16
  JobId:  4397
  Job:sv27.2007-10-22_18.00.07
  Backup Level:   Incremental, since=2007-10-08 18:00:21
  Client: "sv27" 2.0.0 (04Jan07) Linux,Cross-compile,Win32
  FileSet:"sv27" 2007-07-10 09:43:58
  Pool:   "sv27" (From Job resource)
  Storage:"sv27" (From Job resource)
  Scheduled time: 22-Oct-2007 18:00:06
  Start time: 22-Oct-2007 18:01:14
  End time:   22-Oct-2007 18:01:16
  Elapsed time:   2 secs
  Priority:   10
  FD Files Written:   0
  SD Files Written:   0
  FD Bytes Written:   0 (0 B)
  SD Bytes Written:   0 (0 B)
  Rate:   0.0 KB/s
  Software Compression:   None
  VSS:yes
  Encryption: no
  Volume name(s):
  Volume Session Id:  78
  Volume Session Time:1192828139
  Last Volume Bytes:  0 (0 B)
  Non-fatal FD errors:0
  SD Errors:  0
  FD termination status:  Error
  SD termination status:  Error
  Termination:*** Backup