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

Re: [Bacula-users] invalid catalog request again

2009-04-03 11:11:36
Subject: Re: [Bacula-users] invalid catalog request again
From: Jeff Dickens <jeff AT m2.seamanpaper DOT com>
To: bacula-users <bacula-users AT lists.sourceforge DOT net>
Date: Fri, 03 Apr 2009 11:03:37 -0400

Kevin Keane wrote: 
Is there possibly something wrong with the permissions on that file, or 
the /bacula/marie3 directory? Maybe it is only writable by root, not the 
user that the bacula-sd is running as?

Nope, it has the same permissions, owner and group as all my other disk volumes.
[root@packrat ~]# ls -l /bacula/marie3/marie3-diff-0616
-rw-r-----  1 bacula disk 999936243 Apr  3 03:07 /bacula/marie3/marie3-diff-0616
It failed again this morning (with no spooling happening)

03-Apr 03:04 packrat-dir JobId 29673: Start Backup JobId 29673, Job=marie3.2009-04-03_02.05.40
03-Apr 03:04 packrat-dir JobId 29673: Purging oldest volume "marie3-diff-0616"
03-Apr 03:04 packrat-dir JobId 29673: 1 File on Volume "marie3-diff-0616" purged from catalog.
03-Apr 03:04 packrat-dir JobId 29673: There are no more Jobs associated with Volume "marie3-diff-0616". Marking it purged.
03-Apr 03:04 packrat-dir JobId 29673: All records pruned from Volume "marie3-diff-0616"; marking it "Purged"
03-Apr 03:04 packrat-dir JobId 29673: Using Device "marie3-filedev"
03-Apr 03:04 marie3-fd JobId 29673: DIR and FD clocks differ by -6 seconds, FD automatically compensating.
03-Apr 03:04 packrat-sd JobId 29673: Recycled volume "marie3-diff-0616" on device "marie3-filedev" (/bacula/marie3), all previous data lost.
03-Apr 03:04 packrat-dir JobId 29673: Volume used once. Marking Volume "marie3-diff-0616" as Used.
03-Apr 03:04 marie3-fd JobId 29673: Generate VSS snapshots. Driver="VSS WinXP", Drive(s)="C"
03-Apr 03:07 packrat-dir JobId 29673: Fatal error: catreq.c:336 Invalid Catalog request: CatReq Job=marie3.2009-04-03_02.05.40 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936243 VolMounts=7 VolErrors=0 VolWrites=15507 MaxVolBytes=0 EndTime=1238742441 VolStatus=Used Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6645295 VolFirstWritten=0 VolParts=0
03-Apr 03:07 packrat-sd JobId 29673: Fatal error: Error getting Volume info: 1990 Invalid Catalog Request: CatReq Job=marie3.2009-04-03_02.05.40 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936243 VolMounts=7 VolErrors=0 VolWrites=15507 MaxVolBytes=0 EndTime=1238742441 VolStatus=Used Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6645295 VolFirstWritten=0 VolParts=0
03-Apr 03:07 packrat-dir JobId 29673: Fatal error: catreq.c:336 Invalid Catalog request: CatReq Job=marie3.2009-04-03_02.05.40 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936243 VolMounts=7 VolErrors=0 VolWrites=15507 MaxVolBytes=0 EndTime=1238742441 VolStatus=Full Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6645295 VolFirstWritten=0 VolParts=0
03-Apr 03:07 packrat-sd JobId 29673: Fatal error: Error getting Volume info: 1990 Invalid Catalog Request: CatReq Job=marie3.2009-04-03_02.05.40 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936243 VolMounts=7 VolErrors=0 VolWrites=15507 MaxVolBytes=0 EndTime=1238742441 VolStatus=Full Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6645295 VolFirstWritten=0 VolParts=0
03-Apr 03:07 packrat-sd JobId 29673: Job write elapsed time = 00:02:52, Transfer rate = 5.809 M bytes/second
03-Apr 03:07 marie3-fd JobId 29673: Fatal error: ../../filed/backup.c:892 Network send error to SD. ERR=Input/output error
03-Apr 03:07 marie3-fd JobId 29673: Error: ../../lib/bsock.c:306 Write error sending 44544 bytes to Storage daemon:packrat:9103: ERR=Input/output error
03-Apr 03:08 marie3-fd JobId 29673: VSS Writer (BackupComplete): "Microsoft Writer (Service State)", State: 0x1 (VSS_WS_STABLE)
03-Apr 03:08 marie3-fd JobId 29673: VSS Writer (BackupComplete): "Microsoft Writer (Bootable State)", State: 0x1 (VSS_WS_STABLE)
03-Apr 03:08 marie3-fd JobId 29673: VSS Writer (BackupComplete): "WMI Writer", State: 0x1 (VSS_WS_STABLE)
03-Apr 03:09 packrat-dir JobId 29673: Error: Bacula packrat-dir 2.2.8 (26Jan08): 03-Apr-2009 03:09:06
  Build OS:               i686-redhat-linux-gnu redhat 
  JobId:                  29673
  Job:                    marie3.2009-04-03_02.05.40
  Backup Level:           Differential, since=2009-03-24 11:57:50
  Client:                 "marie3-fd" 2.2.8 (26Jan08) Linux,Cross-compile,Win32
  FileSet:                "XP_WS_plus" 2006-07-26 12:31:36
  Pool:                   "marie3-pool-diff" (From Job DiffPool override)
  Storage:                "marie3-filestore" (From Pool resource)
  Scheduled time:         03-Apr-2009 02:05:00
  Start time:             03-Apr-2009 03:04:27
  End time:               03-Apr-2009 03:09:06
  Elapsed time:           4 mins 39 secs
  Priority:               10
  FD Files Written:       1,188
  SD Files Written:       1,188
  FD Bytes Written:       999,089,399 (999.0 MB)
  SD Bytes Written:       999,166,789 (999.1 MB)
  Rate:                   3581.0 KB/s
  Software Compression:   13.7 %
  VSS:                    yes
  Storage Encryption:     no
  Volume name(s):         marie3-diff-0616
  Volume Session Id:      218
  Volume Session Time:    1237910131
  Last Volume Bytes:      245 (245 B)
  Non-fatal FD errors:    1
  SD Errors:              0
  FD termination status:  Error
  SD termination status:  Error
  Termination:            *** Backup Error ***
  
Jeff Dickens wrote:
  
I just finished a dbcheck, which found no major problems, and then I 
ran this job, which failed as follows:

Where it says "could not open: /bacula/marie3/marie3-diff-0616, ERR=No 
such file or directory" that file does exist.  It's 955MB.

Lots of other jobs are working fine, but this one keeps failing.  
Where should I look next?

Thanks in advance.

-------- Original Message --------
Subject: 	Bacula: Backup Fatal Error of marie3-fd Differential 
(marie3.2009-04-02_18.31.03)
Date: 	Thu, 02 Apr 2009 18:36:15 -0400 (EDT)
From: 	(Bacula) jeff AT intranet.seamanpaper DOT com
To: 	jeff AT intranet.seamanpaper DOT com



02-Apr 18:31 packrat-dir JobId 29638: Start Backup JobId 29638, Job=marie3.2009-04-02_18.31.03
02-Apr 18:31 packrat-dir JobId 29638: Purging oldest volume "marie3-diff-0616"
02-Apr 18:31 packrat-dir JobId 29638: 1 File on Volume "marie3-diff-0616" purged from catalog.
02-Apr 18:31 packrat-dir JobId 29638: There are no more Jobs associated with Volume "marie3-diff-0616". Marking it purged.
02-Apr 18:31 packrat-dir JobId 29638: All records pruned from Volume "marie3-diff-0616"; marking it "Purged"
02-Apr 18:31 packrat-dir JobId 29638: Using Device "marie3-filedev"
02-Apr 18:31 marie3-fd JobId 29638: DIR and FD clocks differ by -5 seconds, FD automatically compensating.
02-Apr 18:31 packrat-sd JobId 29638: Recycled volume "marie3-diff-0616" on device "marie3-filedev" (/bacula/marie3), all previous data lost.
02-Apr 18:31 packrat-dir JobId 29638: Volume used once. Marking Volume "marie3-diff-0616" as Used.
02-Apr 18:31 packrat-sd JobId 29638: Spooling data ...
02-Apr 18:31 marie3-fd JobId 29638: Generate VSS snapshots. Driver="VSS WinXP", Drive(s)="C"
02-Apr 18:35 marie3-fd JobId 29638: VSS Writer (BackupComplete): "Microsoft Writer (Bootable State)", State: 0x1 (VSS_WS_STABLE)
02-Apr 18:35 packrat-sd JobId 29638: Job write elapsed time = 00:03:37, Transfer rate = 5.645 M bytes/second
02-Apr 18:35 marie3-fd JobId 29638: VSS Writer (BackupComplete): "Microsoft Writer (Service State)", State: 0x1 (VSS_WS_STABLE)
02-Apr 18:35 marie3-fd JobId 29638: VSS Writer (BackupComplete): "WMI Writer", State: 0x1 (VSS_WS_STABLE)
02-Apr 18:35 packrat-sd JobId 29638: Committing spooled data to Volume "marie3-diff-0616". Despooling 1,226,252,646 bytes ...
02-Apr 18:36 packrat-dir JobId 29638: Fatal error: catreq.c:336 Invalid Catalog request: CatReq Job=marie3.2009-04-02_18.31.03 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936245 VolMounts=6 VolErrors=0 VolWrites=15506 MaxVolBytes=0 EndTime=1238711773 VolStatus=Used Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6863081 VolFirstWritten=0 VolParts=0
02-Apr 18:36 packrat-sd JobId 29638: Fatal error: Error getting Volume info: 1990 Invalid Catalog Request: CatReq Job=marie3.2009-04-02_18.31.03 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936245 VolMounts=6 VolErrors=0 VolWrites=15506 MaxVolBytes=0 EndTime=1238711773 VolStatus=Used Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6863081 VolFirstWritten=0 VolParts=0
02-Apr 18:36 packrat-dir JobId 29638: Fatal error: catreq.c:336 Invalid Catalog request: CatReq Job=marie3.2009-04-02_18.31.03 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936245 VolMounts=6 VolErrors=0 VolWrites=15506 MaxVolBytes=0 EndTime=1238711773 VolStatus=Full Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6863081 VolFirstWritten=0 VolParts=0
02-Apr 18:36 packrat-sd JobId 29638: Fatal error: Error getting Volume info: 1990 Invalid Catalog Request: CatReq Job=marie3.2009-04-02_18.31.03 UpdateMedia VolName=marie3-diff-0616 VolJobs=1 VolFiles=0 VolBlocks=15500 VolBytes=999936245 VolMounts=6 VolErrors=0 VolWrites=15506 MaxVolBytes=0 EndTime=1238711773 VolStatus=Full Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=-6863081 VolFirstWritten=0 VolParts=0
02-Apr 18:36 packrat-sd JobId 29638: Fatal error: spool.c:294 Fatal append error on device "marie3-filedev" (/bacula/marie3): ERR=dev.c:491 Could not open: /bacula/marie3/marie3-diff-0616, ERR=No such file or directory

02-Apr 18:36 packrat-sd JobId 29638: Despooling elapsed time = 00:00:46, Transfer rate = 26.65 M bytes/second
02-Apr 18:36 packrat-dir JobId 29638: Error: Bacula packrat-dir 2.2.8 (26Jan08): 02-Apr-2009 18:36:14
  Build OS:               i686-redhat-linux-gnu redhat 
  JobId:                  29638
  Job:                    marie3.2009-04-02_18.31.03
  Backup Level:           Differential, since=2009-03-24 11:57:50
  Client:                 "marie3-fd" 2.2.8 (26Jan08) Linux,Cross-compile,Win32
  FileSet:                "XP_WS_plus" 2006-07-26 12:31:36
  Pool:                   "marie3-pool-diff" (From Job DiffPool override)
  Storage:                "marie3-filestore" (From Pool resource)
  Scheduled time:         02-Apr-2009 18:31:41
  Start time:             02-Apr-2009 18:31:45
  End time:               02-Apr-2009 18:36:14
  Elapsed time:           4 mins 29 secs
  Priority:               10
  FD Files Written:       2,115
  SD Files Written:       2,115
  FD Bytes Written:       1,224,594,067 (1.224 GB)
  SD Bytes Written:       1,225,020,476 (1.225 GB)
  Rate:                   4552.4 KB/s
  Software Compression:   11.9 %
  VSS:                    yes
  Storage Encryption:     no
  Volume name(s):         marie3-diff-0616
  Volume Session Id:      201
  Volume Session Time:    1237910131
  Last Volume Bytes:      245 (245 B)
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  Error
  Termination:            *** Backup Error ***

  
__________________________________________________________
  Scanned by Google Message Security  - Leaving Seaman Paper

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

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

  
__________________________________________________________
  Scanned by Google Message Security  - Leaving Seaman Paper

Attachment: jeff.vcf
Description: Vcard

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

_______________________________________________
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:  Re: [Bacula-users] don't compress files only in my PC [was: Re: bacula not use all bandwidth of network], Victor Hugo dos Santos
Next by Date:  Re: [Bacula-users] StorEdge C2 autochanger not working, Allan Black
Previous by Thread:  Re: [Bacula-users] invalid catalog request again, Kevin Keane
Next by Thread:  Re: [Bacula-users] invalid catalog request again, Kevin Keane
Indexes:  [Date] [Thread] [Top] [All Lists]