-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Martin Simmons wrote: >>>>>> On Fri, 20 Jul 2007 00:01:19 -0400, Ryan Novosielski said: >> This has gotten a bit more peculiar. Someone please offer up an opinion >> - -- I'd really appreciate it. :) >> >> If you read below (or the subject line), you'd see the error that I got >> when one of my backup jobs ran. Look what happened this time with a >> different job: >> >> - --- >> *run >> A job name must be specified. >> The defined Job resources are: >> 1: Catalog >> 2: RestoreFiles >> 3: NJMSCatalyst >> 4: SystemArchive >> 5: Webmail >> 6: CFMX-dev >> 7: sopris-WEBMAIL >> 8: BigBrother-Display >> 9: SVNrepos >> 10: VistaBKsave >> 11: BigBrother-NET >> Select Job resource (1-11): 9 >> Run Backup job >> JobName: SVNrepos >> Level: Incremental >> Client: lethe-fd >> FileSet: SVNrepos >> Pool: combined_DELTA (From Job resource) >> Storage: helios_DDS (From Pool resource) >> When: 2007-07-19 23:46:37 >> Priority: 10 >> OK to run? (yes/mod/no): yes >> Job queued. JobId=3270 >> You have messages. >> *me >> 19-Jul 23:46 helios-dir: No prior Full backup Job record found. >> 19-Jul 23:46 helios-dir: No prior or suitable Full backup found in >> catalog. Doing FULL backup. >> *me >> 19-Jul 23:46 helios-dir: Start Backup JobId 3270, >> Job=SVNrepos.2007-07-19_23.46.39 >> - --- >> >> However, if you look at my job listing, you will see that this >> (theoretically) is not true: >> >> *list jobname=SVNrepos >> +-------+----------+---------------------+------+-------+----------+-------------+-----------+ >> | JobId | Name | StartTime | Type | Level | JobFiles | >> JobBytes | JobStatus | >> +-------+----------+---------------------+------+-------+----------+-------------+-----------+ >> | 2,967 | SVNrepos | 2007-06-01 23:00:08 | B | I | 0 | >> 0 | A | >> | 3,029 | SVNrepos | 2007-06-12 21:00:07 | B | F | 13,663 | >> 414,107,280 | T | >> | 3,115 | SVNrepos | 2007-06-26 21:00:06 | B | F | 13,718 | >> 414,196,431 | T | >> | 3,166 | SVNrepos | 2007-07-04 23:00:05 | B | I | 0 | >> 0 | f | >> | 3,174 | SVNrepos | 2007-07-05 23:00:09 | B | I | 27 | >> 203,023 | T | >> | 3,183 | SVNrepos | 2007-07-06 23:00:09 | B | I | 87 | >> 92,951 | f | >> | 3,190 | SVNrepos | 2007-07-06 23:18:31 | B | I | 153 | >> 185,703 | T | >> | 3,200 | SVNrepos | 2007-07-09 23:00:12 | B | I | 241 | >> 212,662 | T | >> | 3,207 | SVNrepos | 2007-07-10 21:00:06 | B | F | 14,328 | >> 415,000,246 | T | >> | 3,216 | SVNrepos | 2007-07-11 23:00:09 | B | I | 165 | >> 152,084 | T | >> | 3,224 | SVNrepos | 2007-07-12 23:00:19 | B | I | 59 | >> 43,704 | T | >> | 3,232 | SVNrepos | 2007-07-13 23:00:19 | B | I | 110 | >> 100,620 | T | >> | 3,242 | SVNrepos | 2007-07-16 23:00:11 | B | I | 217 | >> 178,725 | T | >> | 3,250 | SVNrepos | 2007-07-17 23:00:07 | B | I | 173 | >> 168,380 | T | >> | 3,258 | SVNrepos | 2007-07-18 23:00:08 | B | I | 138 | >> 267,183 | T | >> | 3,266 | SVNrepos | 2007-07-19 23:00:07 | B | F | 0 | >> 0 | A | >> | 3,269 | SVNrepos | 2007-07-19 23:44:46 | B | F | 0 | >> 0 | R | >> | 3,270 | SVNrepos | 2007-07-19 23:46:52 | B | F | 0 | >> 0 | R | >> +-------+----------+---------------------+------+-------+----------+-------------+-----------+ >> >> ...a full job just ran on 7/10, and is still in the database. I'm going >> to try a dbcheck or something, but it appears that something is royally >> screwed here. >> >> One other thing I'm not mentioning is that my bacula-sd is >> semi-consistently crashing. I am fine-tuning the btraceback program so >> that I actually get what I need to report this (originally I did not >> have 'dbx' installed, next the user that spawned the 'dbx' was the >> bacula user, but the crashing daemon ran as root -- I fixed this with >> the addition of sudo). >> >> Any help? Something funny is going on. > > Not sure what is happening, but it might be useful to turn on sql query > logging in the database server. > > Look for queries starting with > > SELECT StartTime FROM Job...
Martin, Turned on query logging. Found the following: 1 Query SELECT ClientId,Uname FROM Client WHERE Name='lethe-fd' 1 Query SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='SVNrepos' AND MD5='1T5Pgm+iPz/ak7JcTj/oFC' 1 Query SELECT StartTime FROM Job WHERE JobStatus='T' AND Type='B' AND Level='F' AND Name='SVNrepos' AND ClientId=7 AND FileSetId=28 ORDER BY StartTime DESC LIMIT 1 ...discovered that the FileSet used in the last backup is 15, not 28. I must have flipped "Ignore FileSet Changes" to Off at some point last week and forgot about it. Doh. Thanks for the help! =R >> Ryan Novosielski wrote: >>> I'm somewhat mystified by this problem. I don't know whether to check the >>> database or what. I see that this particular job does not appear to have >>> NEEDED a volume, and so that might be the issue, but I've seen that before >>> and it did not cause this. The only thing special that happened right >>> before this was that the tape was mistakenly marked full when the first >>> backup job attempted to run. I set this to Append and ran the jobs. 7 jobs >>> ran in all, and all others had no problems. >>> >>> ...... Forwarded Message ....... >>> From: "Bacula" <[EMAIL PROTECTED]> >>> To: <[EMAIL PROTECTED]> >>> Date: Wed, 18 Jul 2007 09:54:43 +0000 (%z) >>> Subj: Bacula: Backup Error of vistaadm-fd Incremental >>> >>> 17-Jul 23:00 helios-dir: Start Backup JobId 3251, >>> Job=VistaBKsave.2007-07-17_23.00.04 >>> 17-Jul 23:00 helios-sd: Spooling data ... >>> 17-Jul 23:00 helios-sd: Job write elapsed time = 00:00:02, Transfer rate = >>> 537 bytes/second >>> 17-Jul 23:00 helios-sd: Committing spooled data to Volume "Delta_B". >>> Despooling 0 bytes ... >>> 18-Jul 09:54 helios-sd: Despooling elapsed time = 00:00:01, Transfer rate = >>> 0 bytes/second >>> 18-Jul 09:54 helios-sd: Alert: smartctl version 5.36 [sparc-sun-solaris2.8] >>> Copyright (C) 2002-6 Bruce Allen >>> 18-Jul 09:54 helios-sd: Alert: Home page is >>> http://smartmontools.sourceforge.net/ >>> 18-Jul 09:54 helios-sd: Alert: >>> 18-Jul 09:54 helios-sd: Alert: Smartctl open device: /dev/rmt/0 failed: >>> Device busy >>> 18-Jul 09:54 helios-sd: 3997 Bad alert command: sh -c >>> '/opt/csw/sbin/smartctl -H -l error /dev/rmt/0': ERR=Child exited with code >>> 2. >>> 18-Jul 09:54 helios-sd: Sending spooled attrs to the Director. Despooling >>> 644 bytes ... >>> 18-Jul 09:54 helios-dir: VistaBKsave.2007-07-17_23.00.04 Error: Could not >>> get Job Volume Parameters to update Bootstrap file. ERR=sql_get.c:411 No >>> volumes found for JobId=3251 >>> >>> 18-Jul 09:54 helios-dir: VistaBKsave.2007-07-17_23.00.04 Error: Bacula >>> 2.0.2 (28Jan07): 18-Jul-2007 09:54:43 >>> JobId: 3251 >>> Job: VistaBKsave.2007-07-17_23.00.04 >>> Backup Level: Incremental, since=2007-07-16 23:00:10 >>> Client: "vistaadm-fd" 2.0.2 (28Jan07) >>> sparc-sun-solaris2.8,solaris,5.8 >>> FileSet: "VistaBK" 2006-09-21 23:00:53 >>> Pool: "combined_DELTA" (From Job IncPool override) >>> Storage: "helios_DDS" (From Pool resource) >>> Scheduled time: 17-Jul-2007 23:00:03 >>> Start time: 17-Jul-2007 23:00:07 >>> End time: 18-Jul-2007 09:54:43 >>> Elapsed time: 10 hours 54 mins 36 secs >>> Priority: 10 >>> FD Files Written: 4 >>> SD Files Written: 4 >>> FD Bytes Written: 758 (758 B) >>> SD Bytes Written: 1,074 (1.074 KB) >>> Rate: 0.0 KB/s >>> Software Compression: None >>> VSS: no >>> Encryption: no >>> Volume name(s): >>> Volume Session Id: 74 >>> Volume Session Time: 1183696494 >>> Last Volume Bytes: 121,411,584 (121.4 MB) >>> Non-fatal FD errors: 1 >>> SD Errors: 0 >>> FD termination status: OK >>> SD termination status: OK >>> Termination: *** Backup Error *** >>> >>> 18-Jul 09:54 helios-dir: Begin pruning Jobs. >>> 18-Jul 09:54 helios-dir: No Jobs found to prune. >>> 18-Jul 09:54 helios-dir: Begin pruning Files. >>> 18-Jul 09:54 helios-dir: No Files found to prune. >>> 18-Jul 09:54 helios-dir: End auto prune. >>> >>> >>> >>> ------------------------------------------------------------------------- >>> This SF.net email is sponsored by DB2 Express >>> Download DB2 Express C - the FREE version of DB2 express and take >>> control of your XML. No limits. Just data. Click to get it now. >>> http://sourceforge.net/powerbar/db2/ >>> _______________________________________________ >>> Bacula-users mailing list >>> Bacula-users@lists.sourceforge.net >>> https://lists.sourceforge.net/lists/listinfo/bacula-users >> >> - -- >> ---- _ _ _ _ ___ _ _ _ >> |Y#| | | |\/| | \ |\ | | |Ryan Novosielski - Systems Programmer II >> |$&| |__| | | |__/ | \| _| |[EMAIL PROTECTED] - 973/972.0922 (2-0922) >> \__/ Univ. of Med. and Dent.|IST/AST - NJMS Medical Science Bldg - C630 - -- ---- _ _ _ _ ___ _ _ _ |Y#| | | |\/| | \ |\ | | |Ryan Novosielski - Systems Programmer II |$&| |__| | | |__/ | \| _| |[EMAIL PROTECTED] - 973/972.0922 (2-0922) \__/ Univ. of Med. and Dent.|IST/AST - NJMS Medical Science Bldg - C630 -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.5 (MingW32) Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org iD8DBQFGpRdrmb+gadEcsb4RAlx6AKCj2JTxbMxeBhb0rqFtwufuonoDkACffGtd lQ17adatV2OG7DESUEZHGbo= =zHuk -----END PGP SIGNATURE----- ------------------------------------------------------------------------- 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
