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

[Bacula-users] Bacula "estimate listing" shows the files and attributes, backup job error is "Could not stat FILE=: ERR=No such file or directory"

2008-12-11 15:44:43
Subject: [Bacula-users] Bacula "estimate listing" shows the files and attributes, backup job error is "Could not stat FILE=: ERR=No such file or directory"
From: "Mingus Dew" <shon.stephens AT gmail DOT com>
To: bacula-users <bacula-users AT lists.sourceforge DOT net>
Date: Thu, 11 Dec 2008 15:42:28 -0500
All,
     I am having a very strange problem. My backup server is running Bacula 2.4.2 (Solaris 10_x86) and my client is Bacula 2.2.8 (Solaris 10_x86). This client is having intermittent job failures that I am at a loss to explain. Here is the log output from one of the "failed" jobs. Its completing with an OK status, but is not backing up any files. I know it says "ERR=No such file or directory", but the files are there and "estimate listing" shows the files.

008-12-11 11:32:04mt-back4.director JobId 17587: Start Backup JobId 17587, Job=GCRS_Lvl0_Tape.2008-12-11_11.32.41
2008-12-11 11:32:04mt-back4.director JobId 17587: Using Device "Ultrium-TD3"
2008-12-11 11:32:04mt-back4.storage JobId 17587: 3301 Issuing autochanger "loaded? drive 0" command.
2008-12-11 11:32:07mt-back4.storage JobId 17587: 3302 Autochanger "loaded? drive 0", result: nothing loaded.
2008-12-11 11:32:07mt-back4.storage JobId 17587: 3304 Issuing autochanger "load slot 18, drive 0" command.
2008-12-11 11:34:30mt-back4.storage JobId 17587: 3305 Autochanger "load slot 18, drive 0", status is OK.
2008-12-11 11:34:30mt-back4.storage JobId 17587: Volume "B00041" previously written, moving to end of data.
2008-12-11 11:35:19mt-back4.storage JobId 17587: Ready to append to end of Volume "B00041" at file=142.
2008-12-11 11:35:19mt-back4.storage JobId 17587: Spooling data ...
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
11-Dec 11:35 adm9. JobId 17587:      Could not stat FILE=: ERR=No such file or directory
2008-12-11 11:35:19mt-back4.storage JobId 17587: Job write elapsed time = 00:00:01, Transfer rate = 0  bytes/second
2008-12-11 11:35:19mt-back4.storage JobId 17587: Committing spooled data to Volume "B00041". Despooling 462 bytes ...
2008-12-11 11:35:19mt-back4.storage JobId 17587: Despooling elapsed time = 00:00:01, Transfer rate = 462  bytes/second
2008-12-11 11:35:24mt-back4.storage JobId 17587: Sending spooled attrs to the Director. Despooling 0 bytes ...
2008-12-11 11:35:24mt-back4.director JobId 17587: Bacula mt-back4.director 2.4.2 (26Jul08): 11-Dec-2008 11:35:24
  Build OS:               i386-pc-solaris2.10 solaris 5.10
  JobId:                  17587
  Job:                    GCRS_Lvl0_Tape.2008-12-11_11.32.41
  Backup Level:           Incremental, since=2008-12-08 22:53:45
  Client:                 "adm9." 2.2.8 (26Jan08) i386-pc-solaris2.8,solaris,5.8
  FileSet:                "Gcrs_Lvl0" 2008-11-16 08:03:52
  Pool:                   "Tapes" (From Job resource)
  Storage:                "Exabyte_224" (From Pool resource)
  Scheduled time:         11-Dec-2008 11:31:57
  Start time:             11-Dec-2008 11:32:04
  End time:               11-Dec-2008 11:35:24
  Elapsed time:           3 mins 20 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
  Storage Encryption:     no
  Volume name(s):         B00041
  Volume Session Id:      1391
  Volume Session Time:    1227027150
  Last Volume Bytes:      140,914,916,352 (140.9 GB)
  Non-fatal FD errors:    21
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK -- with warnings

Here is the estimate listing output

Connecting to Client adm9. at 192.168.14.25:9102
-rw-r-----   1 user     100     2071502848 2008-12-07 02:19:36  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_elk1kb7n_1_1
-rw-r-----   1 user     100      641552384 2008-12-07 02:25:04  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_elk1kb7n_2_1
-rw-r-----   1 user     100     2071363584 2008-12-07 02:43:13  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_emk1kck4_1_1
-rw-r-----   1 user     100      723550208 2008-12-07 02:48:11  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_emk1kck4_2_1
-rw-r-----   1 user     100     2071404544 2008-12-07 02:59:56  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_eok1kdvj_1_1
-rw-r-----   1 user     100       77914112 2008-12-07 03:00:52  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_eok1kdvj_2_1
-rw-r-----   1 user     100     2071474176 2008-12-07 03:11:51  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_epk1kenc_1_1
-rw-r-----   1 user     100      355069952 2008-12-07 03:13:48  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_epk1kenc_2_1
-rw-r-----   1 user     100     2071527424 2008-12-07 03:23:49  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_erk1kffh_1_1
-rw-r-----   1 user     100      756748288 2008-12-07 03:28:16  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_erk1kffh_2_1
-rw-r-----   1 user     100     2046550016 2008-12-07 03:37:34  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_etk1kgao_1_1
-rw-r-----   1 user     100     2071408640 2008-12-07 03:47:31  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_euk1kgs3_1_1
-rw-r-----   1 user     100      432750592 2008-12-07 03:49:53  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_euk1kgs3_2_1
-rw-r-----   1 user     100     1990238208 2008-12-07 03:59:57  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_evk1khj8_1_1
-rw-r-----   1 user     100     2071371776 2008-12-07 04:08:31  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f0k1ki66_1_1
-rw-r-----   1 user     100      589840384 2008-12-07 04:11:10  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f0k1ki66_2_1
-rw-r-----   1 user     100     2071306240 2008-12-07 04:19:32  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f1k1kir4_1_1
-rw-r-----   1 user     100      801792000 2008-12-07 04:23:12  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f1k1kir4_2_1
-rw-r-----   1 user     100     2071597056 2008-12-07 04:32:57  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f2k1kjhl_1_1
-rw-r-----   1 user     100      337649664 2008-12-07 04:34:38  /pool1/backup/oracle/GCRS/lvl0/GCRS_lvl0_f2k1kjhl_2_1
-rw-r-----   1 user     100       23134208 2008-12-07 04:35:47  /pool1/backup/oracle/GCRS/controlfile/c-4061640606-20081207-01
2000 OK estimate files=21 bytes=27,419,746,304

Does anyone have any ideas what the problem may be?

Thanks,
Shon
------------------------------------------------------------------------------
SF.Net email is Sponsored by MIX09, March 18-20, 2009 in Las Vegas, Nevada.
The future of the web can't happen without you.  Join us at MIX09 to help
pave the way to the Next Web now. Learn more and register at
http://ad.doubleclick.net/clk;208669438;13503038;i?http://2009.visitmix.com/
_______________________________________________
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>
  • [Bacula-users] Bacula "estimate listing" shows the files and attributes, backup job error is "Could not stat FILE=: ERR=No such file or directory", Mingus Dew <=
Previous by Date:  Re: [Bacula-users] minimize iowait, John Drescher
Next by Date:  Re: [Bacula-users] multi drive changer, limit concurrent jobs for each drive, Ralf Gross
Previous by Thread:  [Bacula-users] how to receive a file list with email notification, Peter
Next by Thread:  [Bacula-users] feature request: "Maximum Concurrent Jobs" for drives when used with changer device, Ralf Gross
Indexes:  [Date] [Thread] [Top] [All Lists]