3 * Bacula Director -- verify.c -- responsible for running file verification
5 * Kern Sibbald, October MM
7 * Basic tasks done here:
9 * Open connection with File daemon and pass him commands
11 * When the File daemon sends the attributes, compare them to
18 Copyright (C) 2000-2004 Kern Sibbald
20 This program is free software; you can redistribute it and/or
21 modify it under the terms of the GNU General Public License as
22 published by the Free Software Foundation; either version 2 of
23 the License, or (at your option) any later version.
25 This program is distributed in the hope that it will be useful,
26 but WITHOUT ANY WARRANTY; without even the implied warranty of
27 MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
28 General Public License for more details.
30 You should have received a copy of the GNU General Public
31 License along with this program; if not, write to the Free
32 Software Foundation, Inc., 59 Temple Place - Suite 330, Boston,
39 #include "findlib/find.h"
41 /* Imported Global Variables */
42 extern int debug_level;
44 /* Commands sent to File daemon */
45 static char verifycmd[] = "verify level=%s\n";
46 static char storaddr[] = "storage address=%s port=%d ssl=0\n";
47 static char sessioncmd[] = "session %s %ld %ld %ld %ld %ld %ld\n";
49 /* Responses received from File daemon */
50 static char OKverify[] = "2000 OK verify\n";
51 static char OKstore[] = "2000 OK storage\n";
52 static char OKsession[] = "2000 OK session\n";
54 /* Forward referenced functions */
55 static void verify_cleanup(JCR *jcr, int TermCode);
56 static void prt_fname(JCR *jcr);
57 static int missing_handler(void *ctx, int num_fields, char **row);
60 * Do a verification of the specified files against the Catlaog
62 * Returns: false on failure
65 bool do_verify(JCR *jcr)
67 const char *level, *Name;
69 JOB_DBR jr, verify_jr;
70 JobId_t verify_jobid = 0;
73 memset(&verify_jr, 0, sizeof(verify_jr));
74 if (!jcr->verify_jr) {
75 jcr->verify_jr = &verify_jr;
77 if (!get_or_create_client_record(jcr)) {
81 Dmsg1(9, "bdird: created client %s record\n", jcr->client->hdr.name);
84 * Find JobId of last job that ran. E.g.
85 * for VERIFY_CATALOG we want the JobId of the last INIT.
86 * for VERIFY_VOLUME_TO_CATALOG, we want the JobId of the
89 if (jcr->JobLevel == L_VERIFY_CATALOG ||
90 jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG ||
91 jcr->JobLevel == L_VERIFY_DISK_TO_CATALOG) {
92 memcpy(&jr, &jcr->jr, sizeof(jr));
93 if (jcr->verify_job &&
94 (jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG ||
95 jcr->JobLevel == L_VERIFY_DISK_TO_CATALOG)) {
96 Name = jcr->verify_job->hdr.name;
100 Dmsg1(100, "find last jobid for: %s\n", NPRT(Name));
101 if (!db_find_last_jobid(jcr, jcr->db, Name, &jr)) {
102 if (jcr->JobLevel == L_VERIFY_CATALOG) {
103 Jmsg(jcr, M_FATAL, 0, _(
104 "Unable to find JobId of previous InitCatalog Job.\n"
105 "Please run a Verify with Level=InitCatalog before\n"
106 "running the current Job.\n"));
108 Jmsg(jcr, M_FATAL, 0, _(
109 "Unable to find JobId of previous Job for this client.\n"));
113 verify_jobid = jr.JobId;
114 Dmsg1(100, "Last full jobid=%d\n", verify_jobid);
117 if (!db_update_job_start_record(jcr, jcr->db, &jcr->jr)) {
118 Jmsg(jcr, M_FATAL, 0, "%s", db_strerror(jcr->db));
123 jcr->fname = get_pool_memory(PM_FNAME);
126 /* Print Job Start message */
127 Jmsg(jcr, M_INFO, 0, _("Start Verify JobId=%d Level=%s Job=%s\n"),
128 jcr->JobId, level_to_str(jcr->JobLevel), jcr->Job);
131 * Now get the job record for the previous backup that interests
132 * us. We use the verify_jobid that we found above.
134 if (jcr->JobLevel == L_VERIFY_CATALOG ||
135 jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG ||
136 jcr->JobLevel == L_VERIFY_DISK_TO_CATALOG) {
137 verify_jr.JobId = verify_jobid;
138 if (!db_get_job_record(jcr, jcr->db, &verify_jr)) {
139 Jmsg(jcr, M_FATAL, 0, _("Could not get job record for previous Job. ERR=%s"),
140 db_strerror(jcr->db));
143 if (verify_jr.JobStatus != 'T') {
144 Jmsg(jcr, M_FATAL, 0, _("Last Job %d did not terminate normally. JobStatus=%c\n"),
145 verify_jobid, verify_jr.JobStatus);
148 Jmsg(jcr, M_INFO, 0, _("Verifying against JobId=%d Job=%s\n"),
149 verify_jr.JobId, verify_jr.Job);
153 * If we are verifying a Volume, we need the Storage
154 * daemon, so open a connection, otherwise, just
155 * create a dummy authorization key (passed to
156 * File daemon but not used).
158 if (jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG) {
159 RBSR *bsr = new_bsr();
161 bsr->JobId = verify_jr.JobId;
162 ua = new_ua_context(jcr);
163 complete_bsr(ua, bsr);
164 bsr->fi = new_findex();
166 bsr->fi->findex2 = verify_jr.JobFiles;
167 jcr->ExpectedFiles = write_bsr_file(ua, bsr);
168 if (jcr->ExpectedFiles == 0) {
175 if (jcr->RestoreBootstrap) {
176 free(jcr->RestoreBootstrap);
178 POOLMEM *fname = get_pool_memory(PM_MESSAGE);
179 Mmsg(fname, "%s/restore.bsr", working_directory);
180 jcr->RestoreBootstrap = bstrdup(fname);
181 free_pool_memory(fname);
184 * Start conversation with Storage daemon
186 set_jcr_job_status(jcr, JS_Blocked);
187 if (!connect_to_storage_daemon(jcr, 10, SDConnectTimeout, 1)) {
191 * Now start a job with the Storage daemon
193 if (!start_storage_daemon_job(jcr)) {
197 * Now start a Storage daemon message thread
199 if (!start_storage_daemon_message_thread(jcr)) {
202 Dmsg0(50, "Storage daemon connection OK\n");
204 jcr->sd_auth_key = bstrdup("dummy"); /* dummy Storage daemon key */
207 if (jcr->JobLevel == L_VERIFY_DISK_TO_CATALOG && jcr->verify_job) {
208 jcr->fileset = jcr->verify_job->fileset;
210 Dmsg2(100, "ClientId=%u JobLevel=%c\n", verify_jr.ClientId, jcr->JobLevel);
213 * OK, now connect to the File daemon
214 * and ask him for the files.
216 set_jcr_job_status(jcr, JS_Blocked);
217 if (!connect_to_file_daemon(jcr, 10, FDConnectTimeout, 1)) {
221 set_jcr_job_status(jcr, JS_Running);
222 fd = jcr->file_bsock;
225 Dmsg0(30, ">filed: Send include list\n");
226 if (!send_include_list(jcr)) {
230 Dmsg0(30, ">filed: Send exclude list\n");
231 if (!send_exclude_list(jcr)) {
236 * Send Level command to File daemon, as well
237 * as the Storage address if appropriate.
239 switch (jcr->JobLevel) {
243 case L_VERIFY_CATALOG:
246 case L_VERIFY_VOLUME_TO_CATALOG:
248 * send Storage daemon address to the File daemon
250 if (jcr->store->SDDport == 0) {
251 jcr->store->SDDport = jcr->store->SDport;
253 bnet_fsend(fd, storaddr, jcr->store->address, jcr->store->SDDport);
254 if (!response(jcr, fd, OKstore, "Storage", DISPLAY_ERROR)) {
259 * Send the bootstrap file -- what Volumes/files to restore
261 if (!send_bootstrap_file(jcr)) {
266 * The following code is deprecated
268 if (!jcr->RestoreBootstrap) {
270 * Pass the VolSessionId, VolSessionTime, Start and
271 * end File and Blocks on the session command.
273 bnet_fsend(fd, sessioncmd,
275 jr.VolSessionId, jr.VolSessionTime,
276 jr.StartFile, jr.EndFile, jr.StartBlock,
278 if (!response(jcr, fd, OKsession, "Session", DISPLAY_ERROR)) {
287 case L_VERIFY_DISK_TO_CATALOG:
288 level="disk_to_catalog";
291 Jmsg2(jcr, M_FATAL, 0, _("Unimplemented Verify level %d(%c)\n"), jcr->JobLevel,
296 if (!send_run_before_and_after_commands(jcr)) {
301 * Send verify command/level to File daemon
303 bnet_fsend(fd, verifycmd, level);
304 if (!response(jcr, fd, OKverify, "Verify", DISPLAY_ERROR)) {
309 * Now get data back from File daemon and
310 * compare it to the catalog or store it in the
311 * catalog depending on the run type.
313 /* Compare to catalog */
314 switch (jcr->JobLevel) {
315 case L_VERIFY_CATALOG:
316 Dmsg0(10, "Verify level=catalog\n");
317 jcr->sd_msg_thread_done = true; /* no SD msg thread, so it is done */
318 jcr->SDJobStatus = JS_Terminated;
319 get_attributes_and_compare_to_catalog(jcr, verify_jobid);
322 case L_VERIFY_VOLUME_TO_CATALOG:
323 Dmsg0(10, "Verify level=volume\n");
324 get_attributes_and_compare_to_catalog(jcr, verify_jobid);
327 case L_VERIFY_DISK_TO_CATALOG:
328 Dmsg0(10, "Verify level=disk_to_catalog\n");
329 jcr->sd_msg_thread_done = true; /* no SD msg thread, so it is done */
330 jcr->SDJobStatus = JS_Terminated;
331 get_attributes_and_compare_to_catalog(jcr, verify_jobid);
336 Dmsg0(10, "Verify level=init\n");
337 jcr->sd_msg_thread_done = true; /* no SD msg thread, so it is done */
338 jcr->SDJobStatus = JS_Terminated;
339 get_attributes_and_put_in_catalog(jcr);
343 Jmsg1(jcr, M_FATAL, 0, _("Unimplemented verify level %d\n"), jcr->JobLevel);
347 stat = wait_for_job_termination(jcr);
348 verify_cleanup(jcr, stat);
352 verify_cleanup(jcr, JS_ErrorTerminated);
357 * Release resources allocated during backup.
360 static void verify_cleanup(JCR *jcr, int TermCode)
362 char sdt[50], edt[50];
363 char ec1[30], ec2[30];
364 char term_code[100], fd_term_msg[100], sd_term_msg[100];
365 const char *term_msg;
370 // Dmsg1(100, "Enter verify_cleanup() TermCod=%d\n", TermCode);
371 dequeue_messages(jcr); /* display any queued messages */
373 Dmsg3(900, "JobLevel=%c Expected=%u JobFiles=%u\n", jcr->JobLevel,
374 jcr->ExpectedFiles, jcr->JobFiles);
375 if (jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG &&
376 jcr->ExpectedFiles != jcr->JobFiles) {
377 TermCode = JS_ErrorTerminated;
380 JobId = jcr->jr.JobId;
381 set_jcr_job_status(jcr, TermCode);
383 update_job_end_record(jcr);
385 msg_type = M_INFO; /* by default INFO message */
388 term_msg = _("Verify OK");
391 case JS_ErrorTerminated:
392 term_msg = _("*** Verify Error ***");
393 msg_type = M_ERROR; /* Generate error message */
396 term_msg = _("Verify warnings");
399 term_msg = _("Verify Canceled");
402 term_msg = _("Verify Differences");
405 term_msg = term_code;
406 bsnprintf(term_code, sizeof(term_code),
407 _("Inappropriate term code: %d %c\n"), TermCode, TermCode);
410 bstrftimes(sdt, sizeof(sdt), jcr->jr.StartTime);
411 bstrftimes(edt, sizeof(edt), jcr->jr.EndTime);
412 if (jcr->verify_job) {
413 Name = jcr->verify_job->hdr.name;
418 jobstatus_to_ascii(jcr->FDJobStatus, fd_term_msg, sizeof(fd_term_msg));
419 if (jcr->JobLevel == L_VERIFY_VOLUME_TO_CATALOG) {
420 jobstatus_to_ascii(jcr->SDJobStatus, sd_term_msg, sizeof(sd_term_msg));
421 Jmsg(jcr, msg_type, 0, _("Bacula " VERSION " (" LSMDATE "): %s\n"
425 " Verify Level: %s\n"
427 " Verify JobId: %d\n"
431 " Files Expected: %s\n"
432 " Files Examined: %s\n"
433 " Non-fatal FD errors: %d\n"
434 " FD termination status: %s\n"
435 " SD termination status: %s\n"
436 " Termination: %s\n\n"),
440 jcr->fileset->hdr.name,
441 level_to_str(jcr->JobLevel),
442 jcr->client->hdr.name,
443 jcr->verify_jr->JobId,
447 edit_uint64_with_commas(jcr->ExpectedFiles, ec1),
448 edit_uint64_with_commas(jcr->JobFiles, ec2),
454 Jmsg(jcr, msg_type, 0, _("Bacula " VERSION " (" LSMDATE "): %s\n"
458 " Verify Level: %s\n"
460 " Verify JobId: %d\n"
464 " Files Examined: %s\n"
465 " Non-fatal FD errors: %d\n"
466 " FD termination status: %s\n"
467 " Termination: %s\n\n"),
471 jcr->fileset->hdr.name,
472 level_to_str(jcr->JobLevel),
473 jcr->client->hdr.name,
474 jcr->verify_jr->JobId,
478 edit_uint64_with_commas(jcr->JobFiles, ec1),
483 Dmsg0(100, "Leave verify_cleanup()\n");
485 free_memory(jcr->fname);
491 * This routine is called only during a Verify
493 int get_attributes_and_compare_to_catalog(JCR *jcr, JobId_t JobId)
498 struct stat statf; /* file stat */
499 struct stat statc; /* catalog stat */
500 int stat = JS_Terminated;
502 POOLMEM *fname = get_pool_memory(PM_MESSAGE);
504 int32_t file_index = 0;
506 memset(&fdbr, 0, sizeof(FILE_DBR));
507 fd = jcr->file_bsock;
511 Dmsg0(20, "bdird: waiting to receive file attributes\n");
513 * Get Attributes and Signature from File daemon
517 * Options or SIG (MD5/SHA1)
522 while ((n=bget_dirmsg(fd)) >= 0 && !job_canceled(jcr)) {
525 char Opts_SIG[MAXSTRING]; /* Verify Opts or MD5/SHA1 signature */
527 fname = check_pool_memory_size(fname, fd->msglen);
528 jcr->fname = check_pool_memory_size(jcr->fname, fd->msglen);
529 Dmsg1(200, "Atts+SIG=%s\n", fd->msg);
530 if ((len = sscanf(fd->msg, "%ld %d %100s", &file_index, &stream,
532 Jmsg3(jcr, M_FATAL, 0, _("bird<filed: bad attributes, expected 3 fields got %d\n"
533 " mslen=%d msg=%s\n"), len, fd->msglen, fd->msg);
537 * We read the Options or Signature into fname
538 * to prevent overrun, now copy it to proper location.
540 bstrncpy(Opts_SIG, fname, sizeof(Opts_SIG));
542 skip_nonspaces(&p); /* skip FileIndex */
544 skip_nonspaces(&p); /* skip Stream */
546 skip_nonspaces(&p); /* skip Opts_SIG */
547 p++; /* skip space */
550 *fn++ = *p++; /* copy filename */
552 *fn = *p++; /* term filename and point to attribs */
555 * Got attributes stream, decode it
557 if (stream == STREAM_UNIX_ATTRIBUTES || stream == STREAM_UNIX_ATTRIBUTES_EX) {
558 int32_t LinkFIf, LinkFIc;
559 Dmsg2(400, "file_index=%d attr=%s\n", file_index, attr);
561 jcr->FileIndex = file_index; /* remember attribute file_index */
562 decode_stat(attr, &statf, &LinkFIf); /* decode file stat packet */
564 jcr->fn_printed = false;
565 pm_strcpy(jcr->fname, fname); /* move filename into JCR */
567 Dmsg2(040, "dird<filed: stream=%d %s\n", stream, jcr->fname);
568 Dmsg1(020, "dird<filed: attr=%s\n", attr);
571 * Find equivalent record in the database
574 if (!db_get_file_attributes_record(jcr, jcr->db, jcr->fname,
575 jcr->verify_jr, &fdbr)) {
576 Jmsg(jcr, M_INFO, 0, _("New file: %s\n"), jcr->fname);
577 Dmsg1(020, _("File not in catalog: %s\n"), jcr->fname);
578 stat = JS_Differences;
582 * mark file record as visited by stuffing the
583 * current JobId, which is unique, into the MarkId field.
585 db_mark_file_record(jcr, jcr->db, fdbr.FileId, jcr->JobId);
588 Dmsg3(400, "Found %s in catalog. inx=%d Opts=%s\n", jcr->fname,
589 file_index, Opts_SIG);
590 decode_stat(fdbr.LStat, &statc, &LinkFIc); /* decode catalog stat */
592 * Loop over options supplied by user and verify the
593 * fields he requests.
595 for (p=Opts_SIG; *p; p++) {
596 char ed1[30], ed2[30];
598 case 'i': /* compare INODEs */
599 if (statc.st_ino != statf.st_ino) {
601 Jmsg(jcr, M_INFO, 0, _(" st_ino differ. Cat: %s File: %s\n"),
602 edit_uint64((uint64_t)statc.st_ino, ed1),
603 edit_uint64((uint64_t)statf.st_ino, ed2));
604 stat = JS_Differences;
607 case 'p': /* permissions bits */
608 if (statc.st_mode != statf.st_mode) {
610 Jmsg(jcr, M_INFO, 0, _(" st_mode differ. Cat: %x File: %x\n"),
611 (uint32_t)statc.st_mode, (uint32_t)statf.st_mode);
612 stat = JS_Differences;
615 case 'n': /* number of links */
616 if (statc.st_nlink != statf.st_nlink) {
618 Jmsg(jcr, M_INFO, 0, _(" st_nlink differ. Cat: %d File: %d\n"),
619 (uint32_t)statc.st_nlink, (uint32_t)statf.st_nlink);
620 stat = JS_Differences;
623 case 'u': /* user id */
624 if (statc.st_uid != statf.st_uid) {
626 Jmsg(jcr, M_INFO, 0, _(" st_uid differ. Cat: %u File: %u\n"),
627 (uint32_t)statc.st_uid, (uint32_t)statf.st_uid);
628 stat = JS_Differences;
631 case 'g': /* group id */
632 if (statc.st_gid != statf.st_gid) {
634 Jmsg(jcr, M_INFO, 0, _(" st_gid differ. Cat: %u File: %u\n"),
635 (uint32_t)statc.st_gid, (uint32_t)statf.st_gid);
636 stat = JS_Differences;
640 if (statc.st_size != statf.st_size) {
642 Jmsg(jcr, M_INFO, 0, _(" st_size differ. Cat: %s File: %s\n"),
643 edit_uint64((uint64_t)statc.st_size, ed1),
644 edit_uint64((uint64_t)statf.st_size, ed2));
645 stat = JS_Differences;
648 case 'a': /* access time */
649 if (statc.st_atime != statf.st_atime) {
651 Jmsg(jcr, M_INFO, 0, _(" st_atime differs\n"));
652 stat = JS_Differences;
656 if (statc.st_mtime != statf.st_mtime) {
658 Jmsg(jcr, M_INFO, 0, _(" st_mtime differs\n"));
659 stat = JS_Differences;
662 case 'c': /* ctime */
663 if (statc.st_ctime != statf.st_ctime) {
665 Jmsg(jcr, M_INFO, 0, _(" st_ctime differs\n"));
666 stat = JS_Differences;
669 case 'd': /* file size decrease */
670 if (statc.st_size > statf.st_size) {
672 Jmsg(jcr, M_INFO, 0, _(" st_size decrease. Cat: %s File: %s\n"),
673 edit_uint64((uint64_t)statc.st_size, ed1),
674 edit_uint64((uint64_t)statf.st_size, ed2));
675 stat = JS_Differences;
678 case '5': /* compare MD5 */
679 Dmsg1(500, "set Do_MD5 for %s\n", jcr->fname);
682 case '1': /* compare SHA1 */
692 * Got SIG Signature from Storage daemon
693 * It came across in the Opts_SIG field.
695 } else if (stream == STREAM_MD5_SIGNATURE || stream == STREAM_SHA1_SIGNATURE) {
696 Dmsg2(400, "stream=SIG inx=%d SIG=%s\n", file_index, Opts_SIG);
698 * When ever we get a signature is MUST have been
699 * preceded by an attributes record, which sets attr_file_index
701 if (jcr->FileIndex != (uint32_t)file_index) {
702 Jmsg2(jcr, M_FATAL, 0, _("MD5/SHA1 index %d not same as attributes %d\n"),
703 file_index, jcr->FileIndex);
707 db_escape_string(buf, Opts_SIG, strlen(Opts_SIG));
708 if (strcmp(buf, fdbr.SIG) != 0) {
710 if (debug_level >= 10) {
711 Jmsg(jcr, M_INFO, 0, _(" %s not same. File=%s Cat=%s\n"),
712 stream==STREAM_MD5_SIGNATURE?"MD5":"SHA1", buf, fdbr.SIG);
714 Jmsg(jcr, M_INFO, 0, _(" %s differs.\n"),
715 stream==STREAM_MD5_SIGNATURE?"MD5":"SHA1");
717 stat = JS_Differences;
722 jcr->JobFiles = file_index;
724 if (is_bnet_error(fd)) {
726 Jmsg2(jcr, M_FATAL, 0, _("bdird<filed: bad attributes from filed n=%d : %s\n"),
731 /* Now find all the files that are missing -- i.e. all files in
732 * the database where the MarkedId != current JobId
734 jcr->fn_printed = false;
735 bsnprintf(buf, sizeof(buf),
736 "SELECT Path.Path,Filename.Name FROM File,Path,Filename "
737 "WHERE File.JobId=%d "
738 "AND File.MarkedId!=%d AND File.PathId=Path.PathId "
739 "AND File.FilenameId=Filename.FilenameId",
741 /* missing_handler is called for each file found */
742 db_sql_query(jcr->db, buf, missing_handler, (void *)jcr);
743 if (jcr->fn_printed) {
744 stat = JS_Differences;
746 free_pool_memory(fname);
747 set_jcr_job_status(jcr, stat);
751 free_pool_memory(fname);
752 set_jcr_job_status(jcr, JS_ErrorTerminated);
757 * We are called here for each record that matches the above
758 * SQL query -- that is for each file contained in the Catalog
759 * that was not marked earlier. This means that the file in
760 * question is a missing file (in the Catalog but not on Disk).
762 static int missing_handler(void *ctx, int num_fields, char **row)
764 JCR *jcr = (JCR *)ctx;
766 if (!jcr->fn_printed) {
767 Jmsg(jcr, M_INFO, 0, "\n");
768 Jmsg(jcr, M_INFO, 0, _("The following files are missing:\n"));
769 jcr->fn_printed = true;
771 Jmsg(jcr, M_INFO, 0, " %s%s\n", row[0]?row[0]:"", row[1]?row[1]:"");
777 * Print filename for verify
779 static void prt_fname(JCR *jcr)
781 if (!jcr->fn_printed) {
782 Jmsg(jcr, M_INFO, 0, _("File: %s\n"), jcr->fname);
783 jcr->fn_printed = TRUE;