Bacula-users
[Top] [All Lists]
<prev [Date] next>
[Advanced]
 <prev [Thread] next>

[Bacula-users] only one copy job runs

2010-06-25 08:23:37
Subject: [Bacula-users] only one copy job runs
From: C.Keschnat AT internet-mit-iQ DOT de
To: bacula-users AT lists.sourceforge DOT net
Date: Fri, 25 Jun 2010 14:19:20 +0200

Hi,
I'm having a problem with copy jobs. When the copy job starts, it correctly chooses the jobs to be copied. But then all but the last job stop with errors. The last job runs perfectly fine. When I then start the copy job again, the chosen JobIDs are the ones from the job before minus the one that ran successfully. So I have to run the job, wait for it to finish, run it again etc. until all jobs are copied.

The "WeeklyScratch" in the fatal errors comes from the jobdef for my copyjob. I had to enter a pool there, otherwise bacula wouldn't start. So I just chose WeeklyScratch figuring it wouldn't be used anyway because I override it in my schedule or when I run the job manually. I don't understand why I need to define a pool in a job definition. Anyway, the override seems to work for the last job to copy, bacula correctly chooses the pool "FridayOD" to read from which has "NextPool = Friday" defined.

Btw: I also cannot simply run a copy job. It does not give me options like the other jobs but just breaks with the same Fatal error as below. I can run it like this though: run job=CopyJob pool=FridayOD yes

Here are the logs of two copy jobs that I ran consecutively. This is directly from the log. I get a job report per mail as usual, the Fatal errors come separately with subject "bacula daemon message" though if that is of any relevance.


25-Jun 09:54 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:54 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:54 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:54 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:54 bacula-dir JobId 10276: The following 5 JobIds were chosen to be copied: 10247,10248,10249,10250,10251
25-Jun 09:54 bacula-dir JobId 10276: Job not run.
25-Jun 09:54 bacula-dir JobId 10276: Error: Could not start migration job.
25-Jun 09:54 bacula-dir JobId 10276: Job not run.
25-Jun 09:54 bacula-dir JobId 10276: Error: Could not start migration job.
25-Jun 09:54 bacula-dir JobId 10276: Job not run.
25-Jun 09:54 bacula-dir JobId 10276: Error: Could not start migration job.
25-Jun 09:54 bacula-dir JobId 10276: Job not run.
25-Jun 09:54 bacula-dir JobId 10276: Error: Could not start migration job.
25-Jun 09:54 bacula-dir JobId 10276: Copying using JobId=10251 Job=india083.2010-06-25_02.05.00_45
25-Jun 09:54 bacula-dir JobId 10276: Bootstrap records written to /opt/bacula/working/bacula-dir.restore.70.bsr
25-Jun 09:54 bacula-dir JobId 10276: Start Copying JobId 10276, Job=CopyJob.2010-06-25_09.54.28_43
25-Jun 09:54 bacula-dir JobId 10276: Using Device "usb-changer-1-drive-0"
25-Jun 09:54 bacula-sd JobId 10276: Ready to read from volume "daily034" on device "FileStorage" (/backup).
25-Jun 09:54 bacula-sd JobId 10276: Volume "usbchanger1_0005_0019" previously written, moving to end of data.
25-Jun 09:54 bacula-sd JobId 10276: Ready to append to end of Volume "usbchanger1_0005_0019" size=525619595
25-Jun 09:54 bacula-sd JobId 10276: Forward spacing Volume "daily034" to file:block 0:2520964703.
25-Jun 09:54 bacula-sd JobId 10276: End of Volume at file 1 on device "FileStorage" (/backup), Volume "daily034"
25-Jun 09:54 bacula-sd JobId 10276: Ready to read from volume "daily035" on device "FileStorage" (/backup).
25-Jun 09:54 bacula-sd JobId 10276: Forward spacing Volume "daily035" to file:block 0:64709.
25-Jun 09:55 bacula-sd JobId 10276: End of Volume at file 1 on device "FileStorage" (/backup), Volume "daily035"
25-Jun 09:55 bacula-sd JobId 10276: Ready to read from volume "daily014" on device "FileStorage" (/backup).
25-Jun 09:55 bacula-sd JobId 10276: Forward spacing Volume "daily014" to file:block 0:64705.
25-Jun 09:56 bacula-sd JobId 10276: End of Volume at file 1 on device "FileStorage" (/backup), Volume "daily014"
25-Jun 09:56 bacula-sd JobId 10276: Ready to read from volume "daily015" on device "FileStorage" (/backup).
25-Jun 09:56 bacula-sd JobId 10276: Forward spacing Volume "daily015" to file:block 0:64705.
25-Jun 09:57 bacula-sd JobId 10276: End of Volume at file 1 on device "FileStorage" (/backup), Volume "daily015"
25-Jun 09:57 bacula-sd JobId 10276: Ready to read from volume "daily001" on device "FileStorage" (/backup).
25-Jun 09:57 bacula-sd JobId 10276: Forward spacing Volume "daily001" to file:block 0:197.
25-Jun 09:57 bacula-sd JobId 10276: End of Volume at file 0 on device "FileStorage" (/backup), Volume "daily001"
25-Jun 09:57 bacula-sd JobId 10276: End of all volumes.
25-Jun 09:58 bacula-dir JobId 10276: Bacula bacula-dir 5.0.2 (28Apr10): 25-Jun-2010 09:58:07
  Build OS:               x86_64-unknown-linux-gnu debian 5.0.4
  Prev Backup JobId:      10251
  Prev Backup Job:        india083.2010-06-25_02.05.00_45
  New Backup JobId:       10277
  Current JobId:          10276
  Current Job:            CopyJob.2010-06-25_09.54.28_43
  Backup Level:           Full
  Client:                 None
  FileSet:                "None" 2010-06-18 17:11:00
  Read Pool:              "FridayOD" (From User input)
  Read Storage:           "File" (From Pool resource)
  Write Pool:             "Friday" (From Job Pool's NextPool resource)
  Write Storage:          "usbchanger1" (From Storage from Pool's NextPool resource)
  Catalog:                "MyCatalog" (From Client resource)
  Start time:             25-Jun-2010 09:54:31
  End time:               25-Jun-2010 09:58:07
  Elapsed time:           3 mins 36 secs
  Priority:               11
  SD Files Written:       15,216
  SD Bytes Written:       2,434,109,688 (2.434 GB)
  Rate:                   11269.0 KB/s
  Volume name(s):         usbchanger1_0005_0019
  Volume Session Id:      260
  Volume Session Time:    1276873992
  Last Volume Bytes:      2,963,216,743 (2.963 GB)
  SD Errors:              0
  SD termination status:  OK
  Termination:            Copying OK -- with warnings



25-Jun 09:59 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:59 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:59 bacula-dir JobId 0: Fatal error: No Next Pool specification found in Pool "WeeklyScratch".
25-Jun 09:59 bacula-dir JobId 10278: The following 4 JobIds were chosen to be copied: 10247,10248,10249,10250
25-Jun 09:59 bacula-dir JobId 10278: Job not run.
25-Jun 09:59 bacula-dir JobId 10278: Error: Could not start migration job.
25-Jun 09:59 bacula-dir JobId 10278: Job not run.
25-Jun 09:59 bacula-dir JobId 10278: Error: Could not start migration job.
25-Jun 09:59 bacula-dir JobId 10278: Job not run.
25-Jun 09:59 bacula-dir JobId 10278: Error: Could not start migration job.
25-Jun 09:59 bacula-dir JobId 10278: Copying using JobId=10250 Job=iavdb.2010-06-25_02.05.00_44
25-Jun 09:59 bacula-dir JobId 10278: Bootstrap records written to /opt/bacula/working/bacula-dir.restore.71.bsr
25-Jun 09:59 bacula-dir JobId 10278: Start Copying JobId 10278, Job=CopyJob.2010-06-25_09.59.28_52
25-Jun 09:59 bacula-dir JobId 10278: Using Device "usb-changer-1-drive-0"
25-Jun 09:59 bacula-sd JobId 10278: Ready to read from volume "daily034" on device "FileStorage" (/backup).
25-Jun 09:59 bacula-sd JobId 10278: Volume "usbchanger1_0005_0019" previously written, moving to end of data.
25-Jun 09:59 bacula-sd JobId 10278: Ready to append to end of Volume "usbchanger1_0005_0019" size=2963216743
25-Jun 09:59 bacula-sd JobId 10278: Forward spacing Volume "daily034" to file:block 0:2508578399.
25-Jun 09:59 bacula-dir JobId 10278: Bacula bacula-dir 5.0.2 (28Apr10): 25-Jun-2010 09:59:33
  Build OS:               x86_64-unknown-linux-gnu debian 5.0.4
  Prev Backup JobId:      10250
  Prev Backup Job:        iavdb.2010-06-25_02.05.00_44
  New Backup JobId:       10279
  Current JobId:          10278
  Current Job:            CopyJob.2010-06-25_09.59.28_52
  Backup Level:           Full
  Client:                 None
  FileSet:                "None" 2010-06-18 17:11:00
  Read Pool:              "FridayOD" (From User input)
  Read Storage:           "File" (From Pool resource)
  Write Pool:             "Friday" (From Job Pool's NextPool resource)
  Write Storage:          "usbchanger1" (From Storage from Pool's NextPool resource)
  Catalog:                "MyCatalog" (From Client resource)
  Start time:             25-Jun-2010 09:59:31
  End time:               25-Jun-2010 09:59:33
  Elapsed time:           2 secs
  Priority:               11
  SD Files Written:       10
  SD Bytes Written:       45,108,388 (45.10 MB)
  Rate:                   22554.2 KB/s
  Volume name(s):         usbchanger1_0005_0019
  Volume Session Id:      261
  Volume Session Time:    1276873992
  Last Volume Bytes:      3,008,364,379 (3.008 GB)
  SD Errors:              0
  SD termination status:  OK
  Termination:            Copying OK -- with warnings

25-Jun 09:59 bacula-dir JobId 10278: Begin pruning Jobs older than 6 months .
25-Jun 09:59 bacula-dir JobId 10278: No Jobs found to prune.
25-Jun 09:59 bacula-dir JobId 10278: Begin pruning Jobs.
25-Jun 09:59 bacula-dir JobId 10278: No Files found to prune.
25-Jun 09:59 bacula-dir JobId 10278: End auto prune.

And a bit of configuration:

JobDefs {
    Name = "CopyDefault"
    Type = Copy
    Messages = Standard
    Client = None
    FileSet = None
    Selection Type = PoolUncopiedJobs
    #Selection Type = SQLQuery
    #Selection Pattern = "select * from Job where JobId=10090"
    Maximum Concurrent Jobs = 10
    SpoolData = No
    Allow Duplicate Jobs = Yes
    Cancel Queued Duplicates = No
    Cancel Running Duplicates = No
    Priority = 11
    Pool = WeeklyScratch
    Storage = File      
}

Job {
    Name = "CopyJob"
    Jobdefs = "CopyDefault"
    Schedule = "CopyCycle"
}

Pool {
  Name = DailyScratch
  Pool Type = Backup
  Recycle Pool = DailyScratch
}

Pool {
  Name = WeeklyScratch
  Pool Type = Backup
  Recycle Pool = WeeklyScratch
}

Pool {
    Name = Friday
    Pool Type = Backup
    Storage = usbchanger1
    Recycle = yes
    AutoPrune = yes
    Volume Retention = 5 days
    Maximum Volume Bytes = 4608M
}

Pool {
    Name = FridayOD
    Pool Type = Backup
    Storage = File
    Recycle = yes
    AutoPrune = yes
    Volume Retention = 12 hours
    Maximum Volume Bytes = 4608M
    ScratchPool = DailyScratch
    NextPool = Friday
}

Schedule {
    Name = "CopyCycle"
    Run = Pool=TuesdayOD tue at 9:05
    Run = Pool=WednesdayOD wed at 9:05
    Run = Pool=ThursdayOD thu at 9:05
    Run = Pool=FridayOD fri at 9:05
    Run = Pool=SecondSaturdayOD 2nd sun at 9:05
    Run = Pool=ThirdSaturdayOD 3rd sun at 9:05
    Run = Pool=FourthSaturdayOD 4th sun at 9:05
    Run = Pool=FifthSaturdayOD 5th sun at 9:05
    Run = Pool=FirstSaturdayJanuaryOD on jan at 1st sun at 9:05
    Run = Pool=FirstSaturdayFebruaryOD on feb at 1st sun at 9:05
    Run = Pool=March2011OD on mar at 1st sun at 9:05
    Run = Pool=FirstSaturdayAprilOD on apr at 1st sun at 9:05
    Run = Pool=FirstSaturdayMayOD on may at 1st sun at 9:05
    Run = Pool=FirstSaturdayJuneOD on jun at 1st sun at 9:05
    Run = Pool=FirstSaturdayJulyOD on jul at 1st sun at 9:05
    Run = Pool=FirstSaturdayAugustOD on aug at 1st sun at 9:05
    Run = Pool=FirstSaturdaySeptemberOD on sep at 1st sun at 9:05
    Run = Pool=FirstSaturdayOctoberOD on oct at 1st sun at 9:05
    Run = Pool=FirstSaturdayNovemberOD on nov at 1st sun at 9:05
    Run = Pool=FirstSaturdayDecemberOD on dec at 1st sun at 9:05
}

Client {
    Name = None
    Address = localhost
    Password = "NoNe"
    Catalog = MyCatalog
}

FileSet {
    Name = None
    Include {
        Options {
            signature = MD5
        }
    }
}

------------------------------------------------------------------------------
ThinkGeek and WIRED's GeekDad team up for the Ultimate 
GeekDad Father's Day Giveaway. ONE MASSIVE PRIZE to the 
lucky parental unit.  See the prize list and enter to win: 
http://p.sf.net/sfu/thinkgeek-promo
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users
[More with this subject...]
<Prev in Thread] Current Thread [Next in Thread>
Previous by Date:  [Bacula-users] Limit backup size, Matthieu Patou
Next by Date:  Re: [Bacula-users] Limit backup size, Thomas Mueller
Previous by Thread:  [Bacula-users] Limit backup size, Matthieu Patou
Next by Thread:  Re: [Bacula-users] only one copy job runs, C . Keschnat
Indexes:  [Date] [Thread] [Top] [All Lists]