--- /dev/null
+
+ This patch fixes several problems: it fixes incorrect or incomplete error
+ messages; it fixes a problem opening the SQLite3 database when multiple
+ simultaneous jobs were running; it fixes a bug with certain versions of
+ MySQL where batch inserts failed because of table name character case
+ (upper/lower) differences.
+
+ It can be applied to version 2.2.4 (and possibly earlier 2.2.x versions)
+ with:
+
+ cd <bacula-source>
+ patch -p0 <2.2.4-sql.patch
+ ./configure (your options)
+ make
+ ...
+ make install
+
+
+
+Index: src/cats/sql.c
+===================================================================
+--- src/cats/sql.c (revision 5687)
++++ src/cats/sql.c (working copy)
+@@ -115,7 +115,6 @@
+
+ bacula_db_version = 0;
+ if (!db_sql_query(mdb, query, int_handler, (void *)&bacula_db_version)) {
+- Mmsg(mdb->errmsg, "Database not created or server not running.\n");
+ Jmsg(jcr, M_FATAL, 0, "%s", mdb->errmsg);
+ return false;
+ }
+Index: src/cats/sqlite.c
+===================================================================
+--- src/cats/sqlite.c (revision 5687)
++++ src/cats/sqlite.c (working copy)
+@@ -148,6 +148,7 @@
+ int len;
+ struct stat statbuf;
+ int errstat;
++ int retry = 0;
+
+ P(mutex);
+ if (mdb->connected) {
+@@ -157,8 +158,9 @@
+ mdb->connected = FALSE;
+
+ if ((errstat=rwl_init(&mdb->lock)) != 0) {
++ berrno be;
+ Mmsg1(&mdb->errmsg, _("Unable to initialize DB lock. ERR=%s\n"),
+- strerror(errstat));
++ be.bstrerror(errstat));
+ V(mutex);
+ return 0;
+ }
+@@ -178,28 +180,28 @@
+ return 0;
+ }
+
++ for (mdb->db=NULL; !mdb->db && retry++ < 10; ) {
+ #ifdef HAVE_SQLITE3
+- int stat = sqlite3_open(db_name, &mdb->db);
+- if (stat != SQLITE_OK) {
+- mdb->sqlite_errmsg = (char *)sqlite3_errmsg(mdb->db);
+- sqlite3_close(mdb->db);
+- mdb->db = NULL;
+- } else {
+- mdb->sqlite_errmsg = NULL;
+- }
+-#ifdef SQLITE3_INIT_QUERY
+- db_sql_query(mdb, SQLITE3_INIT_QUERY, NULL, NULL);
+-#endif
+-
++ int stat = sqlite3_open(db_name, &mdb->db);
++ if (stat != SQLITE_OK) {
++ mdb->sqlite_errmsg = (char *)sqlite3_errmsg(mdb->db);
++ sqlite3_close(mdb->db);
++ mdb->db = NULL;
++ } else {
++ mdb->sqlite_errmsg = NULL;
++ }
+ #else
+- mdb->db = sqlite_open(
+- db_name, /* database name */
+- 644, /* mode */
+- &mdb->sqlite_errmsg); /* error message */
++ mdb->db = sqlite_open(
++ db_name, /* database name */
++ 644, /* mode */
++ &mdb->sqlite_errmsg); /* error message */
+ #endif
+
+- Dmsg0(300, "sqlite_open\n");
+-
++ Dmsg0(300, "sqlite_open\n");
++ if (!mdb->db) {
++ bmicrosleep(1, 0);
++ }
++ }
+ if (mdb->db == NULL) {
+ Mmsg2(&mdb->errmsg, _("Unable to open Database=%s. ERR=%s\n"),
+ db_name, mdb->sqlite_errmsg ? mdb->sqlite_errmsg : _("unknown"));
+@@ -209,10 +211,6 @@
+ }
+ mdb->connected = true;
+ free(db_name);
+- if (!check_tables_version(jcr, mdb)) {
+- V(mutex);
+- return 0;
+- }
+
+ /* set busy handler to wait when we use mult_db_connections = 1 */
+ #ifdef HAVE_SQLITE3
+@@ -221,6 +219,16 @@
+ sqlite_busy_handler(mdb->db, my_busy_handler, NULL);
+ #endif
+
++#if defined(HAVE_SQLITE3) && defined(SQLITE3_INIT_QUERY)
++ db_sql_query(mdb, SQLITE3_INIT_QUERY, NULL, NULL);
++#endif
++
++ if (!check_tables_version(jcr, mdb)) {
++ V(mutex);
++ return 0;
++ }
++
++
+ V(mutex);
+ return 1;
+ }
+@@ -448,16 +456,20 @@
+ return mdb->fields[mdb->field++];
+ }
+
+-char *my_sqlite_batch_lock_query = "BEGIN";
+-char *my_sqlite_batch_unlock_query = "COMMIT";
+-char *my_sqlite_batch_fill_path_query = "INSERT INTO Path (Path) "
+- " SELECT DISTINCT Path FROM batch "
+- " EXCEPT SELECT Path FROM Path ";
++#ifdef HAVE_BATCH_FILE_INSERT
++const char *my_sqlite_batch_lock_query = "BEGIN";
++const char *my_sqlite_batch_unlock_query = "COMMIT";
+
+-char *my_sqlite_batch_fill_filename_query = "INSERT INTO Filename (Name) "
+- " SELECT DISTINCT Name FROM batch "
+- " EXCEPT SELECT Name FROM Filename ";
++const char *my_sqlite_batch_fill_path_query =
++ "INSERT INTO Path (Path)"
++ " SELECT DISTINCT Path FROM batch"
++ " EXCEPT SELECT Path FROM Path";
+
++const char *my_sqlite_batch_fill_filename_query =
++ "INSERT INTO Filename (Name)"
++ " SELECT DISTINCT Name FROM batch "
++ " EXCEPT SELECT Name FROM Filename";
++#endif /* HAVE_BATCH_FILE_INSERT */
+
+
+ #endif /* HAVE_SQLITE */
+Index: src/cats/cats.h
+===================================================================
+--- src/cats/cats.h (revision 5687)
++++ src/cats/cats.h (working copy)
+@@ -187,10 +187,10 @@
+ int my_sqlite_query(B_DB *mdb, const char *cmd);
+ void my_sqlite_field_seek(B_DB *mdb, int field);
+ SQL_FIELD *my_sqlite_fetch_field(B_DB *mdb);
+-extern char* my_sqlite_batch_lock_query;
+-extern char* my_sqlite_batch_unlock_query;
+-extern char* my_sqlite_batch_fill_filename_query;
+-extern char* my_sqlite_batch_fill_path_query;
++extern const char* my_sqlite_batch_lock_query;
++extern const char* my_sqlite_batch_unlock_query;
++extern const char* my_sqlite_batch_fill_filename_query;
++extern const char* my_sqlite_batch_fill_path_query;
+
+
+ #else
+@@ -317,10 +317,10 @@
+ int my_sqlite_query(B_DB *mdb, const char *cmd);
+ void my_sqlite_field_seek(B_DB *mdb, int field);
+ SQL_FIELD *my_sqlite_fetch_field(B_DB *mdb);
+-extern char* my_sqlite_batch_lock_query;
+-extern char* my_sqlite_batch_unlock_query;
+-extern char* my_sqlite_batch_fill_filename_query;
+-extern char* my_sqlite_batch_fill_path_query;
++extern const char* my_sqlite_batch_lock_query;
++extern const char* my_sqlite_batch_unlock_query;
++extern const char* my_sqlite_batch_fill_filename_query;
++extern const char* my_sqlite_batch_fill_path_query;
+
+
+ #else
+@@ -398,11 +398,11 @@
+ #define sql_batch_fill_path_query my_mysql_batch_fill_path_query
+
+
+-extern char* my_mysql_batch_lock_path_query;
+-extern char* my_mysql_batch_lock_filename_query;
+-extern char* my_mysql_batch_unlock_tables_query;
+-extern char* my_mysql_batch_fill_filename_query;
+-extern char* my_mysql_batch_fill_path_query;
++extern const char* my_mysql_batch_lock_path_query;
++extern const char* my_mysql_batch_lock_filename_query;
++extern const char* my_mysql_batch_unlock_tables_query;
++extern const char* my_mysql_batch_fill_filename_query;
++extern const char* my_mysql_batch_fill_path_query;
+ extern void my_mysql_free_result(B_DB *mdb);
+
+ #else
+@@ -486,11 +486,11 @@
+ int my_postgresql_batch_insert(JCR *jcr, B_DB *mdb, ATTR_DBR *ar);
+ char *my_postgresql_copy_escape(char *dest, char *src, size_t len);
+
+-extern char* my_pg_batch_lock_path_query;
+-extern char* my_pg_batch_lock_filename_query;
+-extern char* my_pg_batch_unlock_tables_query;
+-extern char* my_pg_batch_fill_filename_query;
+-extern char* my_pg_batch_fill_path_query;
++extern const char* my_pg_batch_lock_path_query;
++extern const char* my_pg_batch_lock_filename_query;
++extern const char* my_pg_batch_unlock_tables_query;
++extern const char* my_pg_batch_fill_filename_query;
++extern const char* my_pg_batch_fill_path_query;
+
+ /* "Generic" names for easier conversion */
+ #define sql_store_result(x) ((x)->result)
+Index: src/cats/mysql.c
+===================================================================
+--- src/cats/mysql.c (revision 5687)
++++ src/cats/mysql.c (working copy)
+@@ -149,8 +149,9 @@
+ }
+
+ if ((errstat=rwl_init(&mdb->lock)) != 0) {
++ berrno be;
+ Mmsg1(&mdb->errmsg, _("Unable to initialize DB lock. ERR=%s\n"),
+- strerror(errstat));
++ be.bstrerror(errstat));
+ V(mutex);
+ return 0;
+ }
+@@ -403,33 +404,27 @@
+ db_unlock(mdb);
+ }
+
+-char *my_mysql_batch_lock_path_query = "LOCK TABLES Path write, "
+- " batch write, "
+- " Path as p write ";
++#ifdef HAVE_BATCH_FILE_INSERT
++const char *my_mysql_batch_lock_path_query =
++ "LOCK TABLES Path write, batch write, Path as p write";
+
+
+-char *my_mysql_batch_lock_filename_query = "LOCK TABLES Filename write, "
+- " batch write, "
+- " Filename as f write ";
++const char *my_mysql_batch_lock_filename_query =
++ "LOCK TABLES Filename write, batch write, Filename as f write";
+
+-char *my_mysql_batch_unlock_tables_query = "UNLOCK TABLES";
++const char *my_mysql_batch_unlock_tables_query = "UNLOCK TABLES";
+
+-char *my_mysql_batch_fill_path_query = "INSERT INTO Path (Path) "
+- " SELECT a.Path FROM "
+- " (SELECT DISTINCT Path "
+- " FROM batch) AS a "
+- " WHERE NOT EXISTS "
+- " (SELECT Path "
+- " FROM Path AS p "
+- " WHERE p.Path = a.Path) ";
++const char *my_mysql_batch_fill_path_query =
++ "INSERT INTO Path (Path) "
++ "SELECT a.Path FROM "
++ "(SELECT DISTINCT Path FROM batch) AS a WHERE NOT EXISTS "
++ "(SELECT Path FROM Path AS p WHERE p.Path = a.Path)";
+
+-char *my_mysql_batch_fill_filename_query = "INSERT INTO Filename (Name) "
+- " SELECT a.Name FROM "
+- " (SELECT DISTINCT Name "
+- " FROM batch) AS a "
+- " WHERE NOT EXISTS "
+- " (SELECT Name "
+- " FROM Filename AS f "
+- " WHERE f.Name = a.Name) ";
++const char *my_mysql_batch_fill_filename_query =
++ "INSERT INTO Filename (Name) "
++ "SELECT a.Name FROM "
++ "(SELECT DISTINCT Name FROM batch) AS a WHERE NOT EXISTS "
++ "(SELECT Name FROM Filename AS f WHERE f.Name = a.Name)";
++#endif /* HAVE_BATCH_FILE_INSERT */
+
+ #endif /* HAVE_MYSQL */
+Index: src/cats/sql_create.c
+===================================================================
+--- src/cats/sql_create.c (revision 5687)
++++ src/cats/sql_create.c (working copy)
+@@ -668,6 +668,8 @@
+ * };
+ */
+
++#ifdef HAVE_BATCH_FILE_INSERT
++
+ /* All sql_batch_* functions are used to do bulk batch insert in File/Filename/Path
+ * tables. This code can be activated by adding "#define HAVE_BATCH_FILE_INSERT 1"
+ * in baconfig.h
+@@ -690,13 +692,13 @@
+
+ db_lock(mdb);
+ ok = db_sql_query(mdb,
+- " CREATE TEMPORARY TABLE batch "
+- " (fileindex integer, "
+- " jobid integer, "
+- " path blob, "
+- " name blob, "
+- " lstat tinyblob, "
+- " md5 tinyblob) ",NULL, NULL);
++ "CREATE TEMPORARY TABLE batch ("
++ "FileIndex integer,"
++ "JobId integer,"
++ "Path blob,"
++ "Name blob,"
++ "LStat tinyblob,"
++ "MD5 tinyblob)",NULL, NULL);
+ db_unlock(mdb);
+ return ok;
+ }
+@@ -746,7 +748,6 @@
+ return true;
+ }
+
+-#ifdef HAVE_BATCH_FILE_INSERT
+ /*
+ * Returns 1 if OK
+ * 0 if failed
+@@ -794,7 +795,7 @@
+
+ if (!db_sql_query(jcr->db_batch,sql_batch_fill_filename_query, NULL,NULL)) {
+ Jmsg(jcr,M_FATAL,0,"Can't fill Filename table %s\n",jcr->db_batch->errmsg);
+- QUERY_DB(jcr, jcr->db_batch, sql_batch_unlock_tables_query);
++ db_sql_query(jcr->db_batch, sql_batch_unlock_tables_query, NULL, NULL);
+ return false;
+ }
+
+@@ -804,12 +805,12 @@
+ }
+
+ if (!db_sql_query(jcr->db_batch,
+- " INSERT INTO File (FileIndex, JobId, PathId, FilenameId, LStat, MD5)"
+- " SELECT batch.FileIndex, batch.JobId, Path.PathId, "
+- " Filename.FilenameId,batch.LStat, batch.MD5 "
+- " FROM batch "
+- " JOIN Path ON (batch.Path = Path.Path) "
+- " JOIN Filename ON (batch.Name = Filename.Name) ",
++ "INSERT INTO File (FileIndex, JobId, PathId, FilenameId, LStat, MD5)"
++ "SELECT batch.FileIndex, batch.JobId, Path.PathId, "
++ "Filename.FilenameId,batch.LStat, batch.MD5 "
++ "FROM batch "
++ "JOIN Path ON (batch.Path = Path.Path) "
++ "JOIN Filename ON (batch.Name = Filename.Name)",
+ NULL,NULL))
+ {
+ Jmsg(jcr, M_FATAL, 0, "Can't fill File table %s\n", jcr->db_batch->errmsg);
+@@ -845,19 +846,24 @@
+ mdb->db_port,
+ mdb->db_socket,
+ 1 /* multi_db = true */);
++ if (!jcr->db_batch) {
++ Mmsg1(&mdb->errmsg, _("Could not init batch database: \"%s\".\n"),
++ jcr->db->db_name);
++ Jmsg1(jcr, M_FATAL, 0, "%s", mdb->errmsg);
++ return false;
++ }
+
+- if (!jcr->db_batch || !db_open_database(jcr, jcr->db_batch)) {
+- Jmsg(jcr, M_FATAL, 0, _("Could not open database \"%s\".\n"),
+- jcr->db->db_name);
+- if (jcr->db_batch) {
+- Jmsg(jcr, M_FATAL, 0, "%s", db_strerror(jcr->db_batch));
+- }
++ if (!db_open_database(jcr, jcr->db_batch)) {
++ Mmsg2(&mdb->errmsg, _("Could not open database \"%s\": ERR=%s\n"),
++ jcr->db->db_name, db_strerror(jcr->db_batch));
++ Jmsg1(jcr, M_FATAL, 0, "%s", mdb->errmsg);
+ return false;
+ }
+
+ if (!sql_batch_start(jcr, jcr->db_batch)) {
+- Jmsg(jcr, M_FATAL, 0,
+- "Can't start batch mode %s", db_strerror(jcr->db_batch));
++ Mmsg1(&mdb->errmsg,
++ "Can't start batch mode: ERR=%s", db_strerror(jcr->db_batch));
++ Jmsg1(jcr, M_FATAL, 0, "%s", mdb->errmsg);
+ return false;
+ }
+ Dmsg3(100, "initdb ref=%d connected=%d db=%p\n", jcr->db_batch->ref_count,
+@@ -870,10 +876,10 @@
+ */
+ if (!(ar->Stream == STREAM_UNIX_ATTRIBUTES ||
+ ar->Stream == STREAM_UNIX_ATTRIBUTES_EX)) {
+- Mmsg1(&bdb->errmsg, _("Attempt to put non-attributes into catalog. Stream=%d\n"),
++ Mmsg1(&mdb->errmsg, _("Attempt to put non-attributes into catalog. Stream=%d\n"),
+ ar->Stream);
+- Jmsg(jcr, M_ERROR, 0, "%s", bdb->errmsg);
+- return 0;
++ Jmsg(jcr, M_FATAL, 0, "%s", mdb->errmsg);
++ return false;
+ }
+
+ split_path_and_file(jcr, bdb, ar->fname);
+Index: src/cats/postgresql.c
+===================================================================
+--- src/cats/postgresql.c (revision 5687)
++++ src/cats/postgresql.c (working copy)
+@@ -605,13 +605,13 @@
+ Dmsg0(500, "my_postgresql_batch_start started\n");
+
+ if (my_postgresql_query(mdb,
+- " CREATE TEMPORARY TABLE batch "
+- " (fileindex int, "
+- " jobid int, "
+- " path varchar, "
+- " name varchar, "
+- " lstat varchar, "
+- " md5 varchar)") == 1)
++ "CREATE TEMPORARY TABLE batch ("
++ "fileindex int,"
++ "jobid int,"
++ "path varchar,"
++ "name varchar,"
++ "lstat varchar,"
++ "md5 varchar)") == 1)
+ {
+ Dmsg0(500, "my_postgresql_batch_start failed\n");
+ return 1;
+@@ -785,22 +785,29 @@
+ return dest;
+ }
+
+-char *my_pg_batch_lock_path_query = "BEGIN; LOCK TABLE Path IN SHARE ROW EXCLUSIVE MODE";
++#ifdef HAVE_BATCH_FILE_INSERT
++const char *my_pg_batch_lock_path_query =
++ "BEGIN; LOCK TABLE Path IN SHARE ROW EXCLUSIVE MODE";
+
+
+-char *my_pg_batch_lock_filename_query = "BEGIN; LOCK TABLE Filename IN SHARE ROW EXCLUSIVE MODE";
++const char *my_pg_batch_lock_filename_query =
++ "BEGIN; LOCK TABLE Filename IN SHARE ROW EXCLUSIVE MODE";
+
+-char *my_pg_batch_unlock_tables_query = "COMMIT";
++const char *my_pg_batch_unlock_tables_query = "COMMIT";
+
+-char *my_pg_batch_fill_path_query = "INSERT INTO Path (Path) "
+- " SELECT a.Path FROM "
+- " (SELECT DISTINCT Path FROM batch) AS a "
+- " WHERE NOT EXISTS (SELECT Path FROM Path WHERE Path = a.Path) ";
++const char *my_pg_batch_fill_path_query =
++ "INSERT INTO Path (Path) "
++ "SELECT a.Path FROM "
++ "(SELECT DISTINCT Path FROM batch) AS a "
++ "WHERE NOT EXISTS (SELECT Path FROM Path WHERE Path = a.Path) ";
+
+
+-char *my_pg_batch_fill_filename_query = "INSERT INTO Filename (Name) "
+- " SELECT a.Name FROM "
+- " (SELECT DISTINCT Name FROM batch) as a "
+- " WHERE NOT EXISTS "
+- " (SELECT Name FROM Filename WHERE Name = a.Name)";
++const char *my_pg_batch_fill_filename_query =
++ "INSERT INTO Filename (Name) "
++ "SELECT a.Name FROM "
++ "(SELECT DISTINCT Name FROM batch) as a "
++ "WHERE NOT EXISTS "
++ "(SELECT Name FROM Filename WHERE Name = a.Name)";
++#endif /* HAVE_BATCH_FILE_INSERT */
++
+ #endif /* HAVE_POSTGRESQL */
#include "bacula.h"
#include "stored.h"
-#define jid() ((uint32_t)get_jobid_from_tid())
-
const int dbglvl = 50;
static dlist *vol_list = NULL;
} else {
Mmsg(msg, "List from %s: %s at %p no dev\n", imsg, vol->vol_name, vol->vol_name);
}
- Dmsg2(dbglvl, "jid=%u %s", jid(), msg.c_str());
+ Dmsg1(dbglvl, "%s", msg.c_str());
}
#ifdef xxx
memset(vol, 0, sizeof(VOLRES));
vol->vol_name = bstrdup(VolumeName);
vol->dev = dcr->dev;
- Dmsg4(dbglvl, "jid=%u new Vol=%s at %p dev=%s\n", (int)dcr->jcr->JobId,
+ Dmsg3(dbglvl, "new Vol=%s at %p dev=%s\n",
VolumeName, vol->vol_name, vol->dev->print_name());
return vol;
}
ASSERT(dev != NULL);
- Dmsg2(dbglvl, "jid=%u reserve_volume %s\n", jid(), VolumeName);
+ Dmsg1(dbglvl, "reserve_volume %s\n", VolumeName);
/*
* We lock the reservations system here to ensure
* when adding a new volume that no newly scheduled
* because it was probably inserted by another job.
*/
if (strcmp(vol->vol_name, VolumeName) == 0) {
- Dmsg2(dbglvl, "jid=%u OK, vol=%s on device.\n", (int)dcr->jcr->JobId, VolumeName);
+ Dmsg1(dbglvl, "OK, vol=%s on device.\n", VolumeName);
goto get_out; /* Volume already on this device */
} else {
- Dmsg3(dbglvl, "jid=%u reserve_vol free vol=%s at %p\n",
- (int)dcr->jcr->JobId, vol->vol_name, vol->vol_name);
+ Dmsg2(dbglvl, "reserve_vol free vol=%s at %p\n",
+ vol->vol_name, vol->vol_name);
debug_list_volumes("reserve_vol free");
vol_list->remove(vol);
free_vol_item(vol);
*/
vol = (VOLRES *)vol_list->binary_insert(nvol, my_compare);
if (vol != nvol) {
- Dmsg3(dbglvl, "jid=%u Found vol=%s dev-same=%d\n", jid(), vol->vol_name, dev==vol->dev);
+ Dmsg2(dbglvl, "Found vol=%s dev-same=%d\n", vol->vol_name, dev==vol->dev);
/*
* At this point, a Volume with this name already is in the list,
* so we simply release our new Volume entry. Note, this should
* only happen if we are moving the volume from one drive to another.
*/
- Dmsg3(dbglvl, "jid=%u reserve_vol free-tmp vol=%s at %p\n",
- (int)dcr->jcr->JobId, vol->vol_name, vol->vol_name);
+ Dmsg2(dbglvl, "reserve_vol free-tmp vol=%s at %p\n",
+ vol->vol_name, vol->vol_name);
/*
* Clear dev pointer so that free_vol_item() doesn't
* take away our volume.
/* Caller wants to switch Volume to another device */
if (!vol->dev->is_busy()) {
/* OK to move it -- I'm not sure this will work */
- Dmsg4(dbglvl, "==== jid=%u Swap vol=%s from dev=%s to %s\n", jid(), VolumeName,
+ Dmsg3(dbglvl, "==== Swap vol=%s from dev=%s to %s\n", VolumeName,
vol->dev->print_name(), dev->print_name());
vol->dev->vol = NULL; /* take vol from old drive */
vol->dev->VolHdr.VolumeName[0] = 0;
dev->vol = vol; /* point dev at vol */
dev->VolHdr.VolumeName[0] = 0;
} else {
- Dmsg4(dbglvl, "jid=%u Volume busy could not swap vol=%s from dev=%s to %s\n",
- jid(), VolumeName, vol->dev->print_name(), dev->print_name());
+ Dmsg3(dbglvl, "Volume busy could not swap vol=%s from dev=%s to %s\n",
+ VolumeName, vol->dev->print_name(), dev->print_name());
vol = NULL; /* device busy */
}
}
vol.vol_name = bstrdup(dcr->VolumeName);
fvol = (VOLRES *)vol_list->binary_search(&vol, my_compare);
free(vol.vol_name);
- Dmsg3(dbglvl, "jid=%u find_vol=%s found=%d\n", jid(), dcr->VolumeName, fvol!=NULL);
+ Dmsg2(dbglvl, "find_vol=%s found=%d\n", dcr->VolumeName, fvol!=NULL);
debug_list_volumes("find_volume");
unlock_volumes();
return fvol;
if (dcr->reserved_device) {
dcr->reserved_device = false;
dev->reserved_device--;
- Dmsg3(dbglvl, "jid=%u Dec reserve=%d dev=%s\n", jid(), dev->reserved_device, dev->print_name());
+ Dmsg2(dbglvl, "Dec reserve=%d dev=%s\n", dev->reserved_device, dev->print_name());
dcr->reserved_device = false;
/* If we set read mode in reserving, remove it */
if (dev->can_read()) {
DEVICE *dev = dcr->dev;
if (dev->vol == NULL) {
- Dmsg2(dbglvl, "jid=%u vol_unused: no vol on %s\n", (int)dcr->jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "vol_unused: no vol on %s\n", dev->print_name());
debug_list_volumes("null vol cannot unreserve_volume");
return false;
}
if (dev->is_busy()) {
- Dmsg2(dbglvl, "jid=%u vol_unused: no vol on %s\n", (int)dcr->jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "vol_unused: no vol on %s\n", dev->print_name());
debug_list_volumes("dev busy cannot unreserve_volume");
return false;
}
VOLRES *vol;
if (dev->vol == NULL) {
- Dmsg2(dbglvl, "jid=%u No vol on dev %s\n", jid(), dev->print_name());
+ Dmsg1(dbglvl, "No vol on dev %s\n", dev->print_name());
return false;
}
lock_volumes();
vol = dev->vol;
dev->vol = NULL;
vol_list->remove(vol);
- Dmsg3(dbglvl, "jid=%u free_volume %s dev=%s\n", jid(), vol->vol_name, dev->print_name());
+ Dmsg2(dbglvl, "free_volume %s dev=%s\n", vol->vol_name, dev->print_name());
free_vol_item(vol);
debug_list_volumes("free_volume");
unlock_volumes();
lock_volumes();
foreach_dlist(vol, vol_list) {
if (vol->dev) {
- Dmsg3(dbglvl, "jid=%u free vol_list Volume=%s dev=%s\n", jid(),
- vol->vol_name, vol->dev->print_name());
+ Dmsg2(dbglvl, "free vol_list Volume=%s dev=%s\n", vol->vol_name, vol->dev->print_name());
} else {
- Dmsg3(dbglvl, "jid=%u free vol_list Volume=%s dev=%p\n", jid(),
- vol->vol_name, vol->dev);
+ Dmsg2(dbglvl, "free vol_list Volume=%s dev=%p\n", vol->vol_name, vol->dev);
}
free(vol->vol_name);
vol->vol_name = NULL;
{
VOLRES *vol = find_volume(dcr);
if (!vol) {
- Dmsg2(dbglvl, "jid=%u Vol=%s not in use.\n", jid(), dcr->VolumeName);
+ Dmsg1(dbglvl, "Vol=%s not in use.\n", dcr->VolumeName);
return false; /* vol not in list */
}
ASSERT(vol->dev != NULL);
if (dcr->dev == vol->dev) { /* same device OK */
- Dmsg2(dbglvl, "jid=%u Vol=%s on same dev.\n", jid(), dcr->VolumeName);
+ Dmsg1(dbglvl, "Vol=%s on same dev.\n", dcr->VolumeName);
return false;
} else {
- Dmsg4(dbglvl, "jid=%u Vol=%s on %s we have %s\n", jid(), dcr->VolumeName,
+ Dmsg3(dbglvl, "Vol=%s on %s we have %s\n", dcr->VolumeName,
vol->dev->print_name(), dcr->dev->print_name());
}
if (!vol->dev->is_busy()) {
- Dmsg3(dbglvl, "jid=%u Vol=%s dev=%s not busy.\n", jid(), dcr->VolumeName, vol->dev->print_name());
+ Dmsg2(dbglvl, "Vol=%s dev=%s not busy.\n", dcr->VolumeName, vol->dev->print_name());
return false;
} else {
- Dmsg3(dbglvl, "jid=%u Vol=%s dev=%s busy.\n", jid(), dcr->VolumeName, vol->dev->print_name());
+ Dmsg2(dbglvl, "Vol=%s dev=%s busy.\n", dcr->VolumeName, vol->dev->print_name());
}
- Dmsg3(dbglvl, "jid=%u Vol=%s in use by %s.\n", jid(), dcr->VolumeName, vol->dev->print_name());
+ Dmsg2(dbglvl, "Vol=%s in use by %s.\n", dcr->VolumeName, vol->dev->print_name());
return true;
}
dirstore = New(alist(10, not_owned_by_alist));
jcr->reserve_msgs = New(alist(10, not_owned_by_alist));
do {
- Dmsg2(dbglvl, "jid=%u <dird: %s", jid(), dir->msg);
+ Dmsg1(dbglvl, "<dird: %s", dir->msg);
ok = sscanf(dir->msg, use_storage, store_name.c_str(),
media_type.c_str(), pool_name.c_str(),
pool_type.c_str(), &append, &Copy, &Stripe) == 7;
/* Now get all devices */
while (dir->recv() >= 0) {
- Dmsg2(dbglvl, "jid=%u <dird device: %s", jid(), dir->msg);
+ Dmsg1(dbglvl, "<dird device: %s", dir->msg);
ok = sscanf(dir->msg, use_device, dev_name.c_str()) == 1;
if (!ok) {
break;
char *device_name;
if (debug_level >= dbglvl) {
foreach_alist(store, dirstore) {
- Dmsg6(dbglvl, "jid=%u Storage=%s media_type=%s pool=%s pool_type=%s append=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "Storage=%s media_type=%s pool=%s pool_type=%s append=%d\n",
store->name, store->media_type, store->pool_name,
store->pool_type, store->append);
foreach_alist(device_name, store->device) {
- Dmsg2(dbglvl, "jid=%u Device=%s\n", jid(), device_name);
+ Dmsg1(dbglvl, " Device=%s\n", device_name);
}
}
}
rctx.PreferMountedVols = false;
rctx.exact_match = false;
rctx.autochanger_only = true;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
if ((ok = find_suitable_device_for_job(jcr, rctx))) {
rctx.try_low_use_drive = false;
}
rctx.autochanger_only = false;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
if ((ok = find_suitable_device_for_job(jcr, rctx))) {
rctx.PreferMountedVols = true;
rctx.exact_match = true;
rctx.autochanger_only = false;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
if ((ok = find_suitable_device_for_job(jcr, rctx))) {
}
/* Look for any mounted drive */
rctx.exact_match = false;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
if ((ok = find_suitable_device_for_job(jcr, rctx))) {
}
/* Try any drive */
rctx.any_drive = true;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
if ((ok = find_suitable_device_for_job(jcr, rctx))) {
*/
if (repeat++ > 1) { /* try algorithm 3 times */
bmicrosleep(30, 0); /* wait a bit */
- Dmsg1(dbglvl, "jid=%u repeat reserve algorithm\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "repeat reserve algorithm\n");
} else if (!rctx.suitable_device || !wait_for_device(jcr, wait_for_device_retries)) {
- Dmsg1(dbglvl, "jid=%u Fail. !suitable_device || !wait_for_device\n",
- (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "Fail. !suitable_device || !wait_for_device\n");
fail = true;
}
lock_reservations();
dev_name.c_str(), media_type.c_str());
dir->fsend(NO_device, dev_name.c_str());
- Dmsg2(dbglvl, "jid=%u >dird: %s", jid(), dir->msg);
+ Dmsg1(dbglvl, ">dird: %s", dir->msg);
}
} else {
unbash_spaces(dir->msg);
pm_strcpy(jcr->errmsg, dir->msg);
Jmsg(jcr, M_FATAL, 0, _("Failed command: %s\n"), jcr->errmsg);
dir->fsend(BAD_use, jcr->errmsg);
- Dmsg2(dbglvl, "jid=%u >dird: %s", jid(), dir->msg);
+ Dmsg1(dbglvl, ">dird: %s", dir->msg);
}
release_reserve_messages(jcr);
/* Find resource, and make sure we were able to open it */
if (strcmp(rctx.device_name, changer->hdr.name) == 0) {
- Dmsg2(dbglvl, "jid=%u Found changer device %s\n",
- (int)rctx.jcr->JobId, vol->dev->device->hdr.name);
+ Dmsg1(dbglvl, "Found changer device %s\n", vol->dev->device->hdr.name);
return true;
}
- Dmsg2(dbglvl, "jid=%u Incorrect changer device %s\n",
- (int)rctx.jcr->JobId, changer->hdr.name);
+ Dmsg1(dbglvl, "Incorrect changer device %s\n", changer->hdr.name);
return false;
}
} else {
dirstore = jcr->read_store;
}
- Dmsg5(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg4(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only);
dlist *temp_vol_list, *save_vol_list;
VOLRES *vol = NULL;
lock_volumes();
- Dmsg1(dbglvl, "jid=%u lock volumes\n", (uint32_t)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "lock volumes\n");
/*
* Create a temporary copy of the volume list. We do this,
* we can take note and act accordingly (probably redo the
* search at least a few times).
*/
- Dmsg1(dbglvl, "jid=%u duplicate vol list\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "duplicate vol list\n");
temp_vol_list = New(dlist(vol, &vol->link));
foreach_dlist(vol, vol_list) {
VOLRES *nvol;
Jmsg(jcr, M_WARNING, 0, "Logic error. Duplicating vol list hit duplicate.\n");
}
}
- Dmsg1(dbglvl, "jid=%u unlock volumes\n", (uint32_t)jcr->JobId);
+ Dmsg0(dbglvl, "unlock volumes\n");
unlock_volumes();
/* Look through reserved volumes for one we can use */
- Dmsg1(dbglvl, "jid=%u look for vol in vol list\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "look for vol in vol list\n");
foreach_dlist(vol, temp_vol_list) {
if (!vol->dev) {
- Dmsg2(dbglvl, "jid=%u vol=%s no dev\n", (int)rctx.jcr->JobId, vol->vol_name);
+ Dmsg1(dbglvl, "vol=%s no dev\n", vol->vol_name);
continue;
}
/* Check with Director if this Volume is OK */
continue;
}
- Dmsg2(dbglvl, "jid=%u vol=%s OK for this job\n", (int)rctx.jcr->JobId, vol->vol_name);
+ Dmsg1(dbglvl, "vol=%s OK for this job\n", vol->vol_name);
foreach_alist(store, dirstore) {
int stat;
rctx.store = store;
rctx.device = vol->dev->device;
if (vol->dev->is_autochanger()) {
- Dmsg2(dbglvl, "jid=%u vol=%s is in changer\n", (int)rctx.jcr->JobId,
- vol->vol_name);
+ Dmsg1(dbglvl, "vol=%s is in changer\n", vol->vol_name);
if (!is_vol_in_autochanger(rctx, vol)) {
continue;
}
} else if (strcmp(device_name, vol->dev->device->hdr.name) != 0) {
- Dmsg3(dbglvl, "jid=%u device=%s not suitable want %s\n", (int)rctx.jcr->JobId,
+ Dmsg2(dbglvl, "device=%s not suitable want %s\n",
vol->dev->device->hdr.name, device_name);
continue;
}
bstrncpy(rctx.VolumeName, vol->vol_name, sizeof(rctx.VolumeName));
rctx.have_volume = true;
/* Try reserving this device and volume */
- Dmsg3(dbglvl, "jid=%u try vol=%s on device=%s\n", (int)rctx.jcr->JobId,
- rctx.VolumeName, device_name);
+ Dmsg2(dbglvl, "try vol=%s on device=%s\n", rctx.VolumeName, device_name);
stat = reserve_device(rctx);
if (stat == 1) { /* found available device */
- Dmsg2(dbglvl, "jid=%u Suitable device found=%s\n", (int)rctx.jcr->JobId,
- device_name);
+ Dmsg1(dbglvl, "Suitable device found=%s\n", device_name);
ok = true;
break;
} else if (stat == 0) { /* device busy */
- Dmsg2(dbglvl, "jid=%u Suitable device=%s, busy: not use\n",
- (int)rctx.jcr->JobId, device_name);
+ Dmsg1(dbglvl, "Suitable device=%s, busy: not use\n", device_name);
} else {
/* otherwise error */
- Dmsg1(dbglvl, "jid=%u No suitable device found.\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "No suitable device found.\n");
}
rctx.have_volume = false;
}
}
} /* end for loop over reserved volumes */
- Dmsg1(dbglvl, "jid=%u lock volumes\n", (uint32_t)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "lock volumes\n");
lock_volumes();
save_vol_list = vol_list;
vol_list = temp_vol_list;
free_volume_list(); /* release temp_vol_list */
vol_list = save_vol_list;
- Dmsg1(dbglvl, "jid=%u deleted temp vol list\n", (int)rctx.jcr->JobId);
- Dmsg1(dbglvl, "jid=%u lock volumes\n", (uint32_t)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "deleted temp vol list\n");
+ Dmsg0(dbglvl, "lock volumes\n");
unlock_volumes();
}
if (ok) {
- Dmsg2(dbglvl, "jid=%u got vol %s from in-use vols list\n", (int)rctx.jcr->JobId,
- rctx.VolumeName);
+ Dmsg1(dbglvl, "got vol %s from in-use vols list\n", rctx.VolumeName);
return true;
}
rctx.device_name = device_name;
stat = search_res_for_device(rctx);
if (stat == 1) { /* found available device */
- Dmsg2(dbglvl, "jid=%u available device found=%s\n", (int)rctx.jcr->JobId,
- device_name);
+ Dmsg1(dbglvl, "available device found=%s\n", device_name);
ok = true;
break;
} else if (stat == 0) { /* device busy */
- Dmsg2(dbglvl, "jid=%u Suitable device=%s, busy: not use\n",
- (int)rctx.jcr->JobId, device_name);
+ Dmsg1(dbglvl, "Suitable device=%s, busy: not use\n", device_name);
} else {
/* otherwise error */
- Dmsg1(dbglvl, "jid=%u No suitable device found.\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "No suitable device found.\n");
}
}
if (ok) {
AUTOCHANGER *changer;
int stat;
- Dmsg2(dbglvl, "jid=%u search res for %s\n", (int)rctx.jcr->JobId, rctx.device_name);
+ Dmsg1(dbglvl, "search res for %s\n", rctx.device_name);
/* Look through Autochangers first */
foreach_res(changer, R_AUTOCHANGER) {
- Dmsg2(dbglvl, "jid=%u Try match changer res=%s\n", (int)rctx.jcr->JobId, changer->hdr.name);
+ Dmsg1(dbglvl, "Try match changer res=%s\n", changer->hdr.name);
/* Find resource, and make sure we were able to open it */
if (strcmp(rctx.device_name, changer->hdr.name) == 0) {
/* Try each device in this AutoChanger */
foreach_alist(rctx.device, changer->device) {
- Dmsg2(dbglvl, "jid=%u Try changer device %s\n", (int)rctx.jcr->JobId,
- rctx.device->hdr.name);
+ Dmsg1(dbglvl, "Try changer device %s\n", rctx.device->hdr.name);
stat = reserve_device(rctx);
if (stat != 1) { /* try another device */
continue;
}
/* Debug code */
if (rctx.store->append == SD_APPEND) {
- Dmsg3(dbglvl, "jid=%u Device %s reserved=%d for append.\n",
- (int)rctx.jcr->JobId, rctx.device->hdr.name,
- rctx.jcr->dcr->dev->reserved_device);
+ Dmsg2(dbglvl, "Device %s reserved=%d for append.\n",
+ rctx.device->hdr.name, rctx.jcr->dcr->dev->reserved_device);
} else {
- Dmsg3(dbglvl, "jid=%u Device %s reserved=%d for read.\n",
- (int)rctx.jcr->JobId, rctx.device->hdr.name,
- rctx.jcr->read_dcr->dev->reserved_device);
+ Dmsg2(dbglvl, "Device %s reserved=%d for read.\n",
+ rctx.device->hdr.name, rctx.jcr->read_dcr->dev->reserved_device);
}
return stat;
}
/* Now if requested look through regular devices */
if (!rctx.autochanger_only) {
foreach_res(rctx.device, R_DEVICE) {
- Dmsg2(dbglvl, "jid=%u Try match res=%s\n", (int)rctx.jcr->JobId, rctx.device->hdr.name);
+ Dmsg1(dbglvl, "Try match res=%s\n", rctx.device->hdr.name);
/* Find resource, and make sure we were able to open it */
if (strcmp(rctx.device_name, rctx.device->hdr.name) == 0) {
stat = reserve_device(rctx);
}
/* Debug code */
if (rctx.store->append == SD_APPEND) {
- Dmsg3(dbglvl, "jid=%u Device %s reserved=%d for append.\n",
- (int)rctx.jcr->JobId, rctx.device->hdr.name,
- rctx.jcr->dcr->dev->reserved_device);
+ Dmsg2(dbglvl, "Device %s reserved=%d for append.\n",
+ rctx.device->hdr.name, rctx.jcr->dcr->dev->reserved_device);
} else {
- Dmsg3(dbglvl, "jid=%u Device %s reserved=%d for read.\n",
- (int)rctx.jcr->JobId, rctx.device->hdr.name,
- rctx.jcr->read_dcr->dev->reserved_device);
+ Dmsg2(dbglvl, "Device %s reserved=%d for read.\n",
+ rctx.device->hdr.name, rctx.jcr->read_dcr->dev->reserved_device);
}
return stat;
}
const int name_len = MAX_NAME_LENGTH;
/* Make sure MediaType is OK */
- Dmsg3(dbglvl, "jid=%u chk MediaType device=%s request=%s\n",
- (int)rctx.jcr->JobId,
+ Dmsg2(dbglvl, "chk MediaType device=%s request=%s\n",
rctx.device->media_type, rctx.store->media_type);
if (strcmp(rctx.device->media_type, rctx.store->media_type) != 0) {
return -1;
}
rctx.suitable_device = true;
- Dmsg2(dbglvl, "jid=%u try reserve %s\n", rctx.jcr->JobId, rctx.device->hdr.name);
+ Dmsg1(dbglvl, "try reserve %s\n", rctx.device->hdr.name);
rctx.jcr->dcr = dcr = new_dcr(rctx.jcr, rctx.jcr->dcr, rctx.device->dev);
if (!dcr) {
BSOCK *dir = rctx.jcr->dir_bsock;
bstrncpy(dcr->media_type, rctx.store->media_type, name_len);
bstrncpy(dcr->dev_name, rctx.device_name, name_len);
if (rctx.store->append == SD_APPEND) {
- Dmsg3(dbglvl, "jid=%u have_vol=%d vol=%s\n", (int)rctx.jcr->JobId,
- rctx.have_volume, rctx.VolumeName);
+ Dmsg2(dbglvl, "have_vol=%d vol=%s\n", rctx.have_volume, rctx.VolumeName);
ok = reserve_device_for_append(dcr, rctx);
if (!ok) {
goto bail_out;
}
rctx.jcr->dcr = dcr;
- Dmsg6(dbglvl, "jid=%u Reserved=%d dev_name=%s mediatype=%s pool=%s ok=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "Reserved=%d dev_name=%s mediatype=%s pool=%s ok=%d\n",
dcr->dev->reserved_device,
dcr->dev_name, dcr->media_type, dcr->pool_name, ok);
if (!rctx.have_volume) {
dcr->any_volume = true;
if (dir_find_next_appendable_volume(dcr)) {
bstrncpy(rctx.VolumeName, dcr->VolumeName, sizeof(rctx.VolumeName));
- Dmsg2(dbglvl, "jid=%u looking for Volume=%s\n", (int)rctx.jcr->JobId, rctx.VolumeName);
+ Dmsg1(dbglvl, "looking for Volume=%s\n", rctx.VolumeName);
rctx.have_volume = true;
} else {
- Dmsg1(dbglvl, "jid=%u No next volume found\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "No next volume found\n");
rctx.have_volume = false;
rctx.VolumeName[0] = 0;
/*
ok = reserve_device_for_read(dcr);
if (ok) {
rctx.jcr->read_dcr = dcr;
- Dmsg6(dbglvl, "jid=%u Read reserved=%d dev_name=%s mediatype=%s pool=%s ok=%d\n",
- (int)rctx.jcr->JobId,
+ Dmsg5(dbglvl, "Read reserved=%d dev_name=%s mediatype=%s pool=%s ok=%d\n",
dcr->dev->reserved_device,
dcr->dev_name, dcr->media_type, dcr->pool_name, ok);
}
pm_strcpy(dev_name, rctx.device->hdr.name);
bash_spaces(dev_name);
ok = dir->fsend(OK_device, dev_name.c_str()); /* Return real device name */
- Dmsg2(dbglvl, "jid=%u >dird: %s", jid(), dir->msg);
+ Dmsg1(dbglvl, ">dird: %s", dir->msg);
} else {
ok = true;
}
bail_out:
rctx.have_volume = false;
// free_dcr(dcr);
- Dmsg1(dbglvl, "jid=%u Not OK.\n", (int)rctx.jcr->JobId);
+ Dmsg0(dbglvl, "Not OK.\n");
return 0;
}
dev->dlock();
if (is_device_unmounted(dev)) {
- Dmsg2(dbglvl, "jid=%u Device %s is BLOCKED due to user unmount.\n",
- (int)jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "Device %s is BLOCKED due to user unmount.\n", dev->print_name());
Mmsg(jcr->errmsg, _("3601 JobId=%u device %s is BLOCKED due to user unmount.\n"),
jcr->JobId, dev->print_name());
queue_reserve_message(jcr);
}
if (dev->is_busy()) {
- Dmsg5(dbglvl, "jid=%u Device %s is busy ST_READ=%d num_writers=%d reserved=%d.\n",
- (int)jcr->JobId, dev->print_name(),
+ Dmsg4(dbglvl, "Device %s is busy ST_READ=%d num_writers=%d reserved=%d.\n",
+ dev->print_name(),
dev->state & ST_READ?1:0, dev->num_writers, dev->reserved_device);
Mmsg(jcr->errmsg, _("3602 JobId=%u device %s is busy (already reading/writing).\n"),
jcr->JobId, dev->print_name());
dev->set_read();
ok = true;
dev->reserved_device++;
- Dmsg4(dbglvl, "jid=%u Inc reserve=%d dev=%s %p\n", (int)jcr->JobId,
- dev->reserved_device, dev->print_name(), dev);
+ Dmsg3(dbglvl, "Inc reserve=%d dev=%s %p\n", dev->reserved_device, dev->print_name(), dev);
dcr->reserved_device = true;
bail_out:
if (dev->can_read()) {
Mmsg(jcr->errmsg, _("3603 JobId=%u device %s is busy reading.\n"),
jcr->JobId, dev->print_name());
- Dmsg2(dbglvl, "jid=%u %s", jid(), jcr->errmsg);
+ Dmsg1(dbglvl, "%s", jcr->errmsg);
queue_reserve_message(jcr);
goto bail_out;
}
if (is_device_unmounted(dev)) {
Mmsg(jcr->errmsg, _("3604 JobId=%u device %s is BLOCKED due to user unmount.\n"),
jcr->JobId, dev->print_name());
- Dmsg2(dbglvl, "jid=%u %s", jid(), jcr->errmsg);
+ Dmsg1(dbglvl, "%s", jcr->errmsg);
queue_reserve_message(jcr);
goto bail_out;
}
- Dmsg2(dbglvl, "jid=%u reserve_append device is %s\n",
- (int)jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "reserve_append device is %s\n", dev->print_name());
/* Now do detailed tests ... */
if (can_reserve_drive(dcr, rctx) != 1) {
- Dmsg1(dbglvl, "jid=%u can_reserve_drive!=1\n", (int)jcr->JobId);
+ Dmsg0(dbglvl, "can_reserve_drive!=1\n");
goto bail_out;
}
dev->reserved_device++;
- Dmsg4(dbglvl, "jid=%u Inc reserve=%d dev=%s %p\n", (int)jcr->JobId, dev->reserved_device,
+ Dmsg3(dbglvl, "Inc reserve=%d dev=%s %p\n", dev->reserved_device,
dev->print_name(), dev);
dcr->reserved_device = true;
ok = true;
if (strcmp(dev->pool_name, dcr->pool_name) == 0 &&
strcmp(dev->pool_type, dcr->pool_type) == 0) {
/* OK, compatible device */
- Dmsg2(dbglvl, "jid=%u OK dev: %s num_writers=0, reserved, pool matches\n",
- jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "OK dev: %s num_writers=0, reserved, pool matches\n", dev->print_name());
return 1;
} else {
/* Drive Pool not suitable for us */
jcr->JobId, dcr->pool_name, dev->pool_name,
dev->reserved_device, dev->print_name());
queue_reserve_message(jcr);
- Dmsg3(dbglvl, "jid=%u failed: busy num_writers=0, reserved, pool=%s wanted=%s\n",
- (int)jcr->JobId, dev->pool_name, dcr->pool_name);
+ Dmsg2(dbglvl, "failed: busy num_writers=0, reserved, pool=%s wanted=%s\n",
+ dev->pool_name, dcr->pool_name);
}
return 0;
}
DEVICE *dev = dcr->dev;
JCR *jcr = dcr->jcr;
- Dmsg5(dbglvl, "jid=%u MaxJobs=%d Jobs=%d reserves=%d Vol=%s\n",
- (uint32_t)jcr->JobId, dcr->VolCatInfo.VolCatMaxJobs,
+ Dmsg4(dbglvl, "MaxJobs=%d Jobs=%d reserves=%d Vol=%s\n",
+ dcr->VolCatInfo.VolCatMaxJobs,
dcr->VolCatInfo.VolCatJobs, dev->reserved_device,
dcr->VolumeName);
if (dcr->VolCatInfo.VolCatMaxJobs > 0 && dcr->VolCatInfo.VolCatMaxJobs <=
Mmsg(jcr->errmsg, _("3610 JobId=%u Volume max jobs exceeded on drive %s.\n"),
(uint32_t)jcr->JobId, dev->print_name());
queue_reserve_message(jcr);
- Dmsg2(dbglvl, "jid=%u reserve dev failed: %s", (uint32_t)jcr->JobId, jcr->errmsg);
+ Dmsg1(dbglvl, "reserve dev failed: %s", jcr->errmsg);
return false; /* wait */
}
return true;
DEVICE *dev = dcr->dev;
JCR *jcr = dcr->jcr;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
* helps spread the load to the least used drives.
*/
if (rctx.try_low_use_drive && dev == rctx.low_use_drive) {
- Dmsg3(dbglvl, "jid=%u OK dev=%s == low_drive=%s.\n",
- jcr->JobId, dev->print_name(), rctx.low_use_drive->print_name());
+ Dmsg2(dbglvl, "OK dev=%s == low_drive=%s.\n",
+ dev->print_name(), rctx.low_use_drive->print_name());
return 1;
}
/* If he wants a free drive, but this one is busy, no go */
if ((dev->num_writers + dev->reserved_device) < rctx.num_writers) {
rctx.num_writers = dev->num_writers + dev->reserved_device;
rctx.low_use_drive = dev;
- Dmsg3(dbglvl, "jid=%u set low use drive=%s num_writers=%d\n",
- (int)jcr->JobId, dev->print_name(), rctx.num_writers);
+ Dmsg2(dbglvl, "set low use drive=%s num_writers=%d\n",
+ dev->print_name(), rctx.num_writers);
} else {
- Dmsg2(dbglvl, "jid=%u not low use num_writers=%d\n",
- (int)jcr->JobId, dev->num_writers+dev->reserved_device);
+ Dmsg1(dbglvl, "not low use num_writers=%d\n", dev->num_writers+dev->reserved_device);
}
- Dmsg1(dbglvl, "jid=%u failed: !prefMnt && busy.\n", jcr->JobId);
+ Dmsg0(dbglvl, "failed: !prefMnt && busy.\n");
Mmsg(jcr->errmsg, _("3605 JobId=%u wants free drive but device %s is busy.\n"),
jcr->JobId, dev->print_name());
queue_reserve_message(jcr);
Mmsg(jcr->errmsg, _("3606 JobId=%u prefers mounted drives, but drive %s has no Volume.\n"),
jcr->JobId, dev->print_name());
queue_reserve_message(jcr);
- Dmsg1(dbglvl, "jid=%u failed: want mounted -- no vol\n", (uint32_t)jcr->JobId);
+ Dmsg0(dbglvl, "failed: want mounted -- no vol\n");
return 0; /* No volume mounted */
}
*/
if (rctx.exact_match && rctx.have_volume) {
bool ok;
- Dmsg6(dbglvl, "jid=%u PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
- (int)jcr->JobId,
+ Dmsg5(dbglvl, "PrefMnt=%d exact=%d suitable=%d chgronly=%d any=%d\n",
rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
rctx.autochanger_only, rctx.any_drive);
- Dmsg5(dbglvl, "jid=%u have_vol=%d have=%s resvol=%s want=%s\n",
- (int)jcr->JobId, rctx.have_volume, dev->VolHdr.VolumeName,
+ Dmsg4(dbglvl, "have_vol=%d have=%s resvol=%s want=%s\n",
+ rctx.have_volume, dev->VolHdr.VolumeName,
dev->vol?dev->vol->vol_name:"*none*", rctx.VolumeName);
ok = strcmp(dev->VolHdr.VolumeName, rctx.VolumeName) == 0 ||
(dev->vol && strcmp(dev->vol->vol_name, rctx.VolumeName) == 0);
jcr->JobId, rctx.VolumeName, dev->VolHdr.VolumeName,
dev->print_name());
queue_reserve_message(jcr);
- Dmsg4(dbglvl, "jid=%u not OK: dev have=%s resvol=%s want=%s\n",
- (int)jcr->JobId, dev->VolHdr.VolumeName,
- dev->vol?dev->vol->vol_name:"*none*", rctx.VolumeName);
+ Dmsg3(dbglvl, "not OK: dev have=%s resvol=%s want=%s\n",
+ dev->VolHdr.VolumeName, dev->vol?dev->vol->vol_name:"*none*", rctx.VolumeName);
return 0;
}
if (is_volume_in_use(dcr)) {
if (rctx.autochanger_only && !dev->is_busy() &&
dev->VolHdr.VolumeName[0] == 0) {
/* Device is available but not yet reserved, reserve it for us */
- Dmsg2(dbglvl, "jid=%u OK Res Unused autochanger %s.\n",
- jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "OK Res Unused autochanger %s.\n", dev->print_name());
bstrncpy(dev->pool_name, dcr->pool_name, sizeof(dev->pool_name));
bstrncpy(dev->pool_type, dcr->pool_type, sizeof(dev->pool_type));
return 1; /* reserve drive */
return 1;
} else {
/* Changing pool, unload old tape if any in drive */
- Dmsg1(dbglvl, "jid=%u OK dev: num_writers=0, not reserved, pool change, unload changer\n",
- (int)jcr->JobId);
+ Dmsg0(dbglvl, "OK dev: num_writers=0, not reserved, pool change, unload changer\n");
unload_autochanger(dcr, 0);
}
}
/* Device is available but not yet reserved, reserve it for us */
- Dmsg2(dbglvl, "jid=%u OK Dev avail reserved %s\n", jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "OK Dev avail reserved %s\n", dev->print_name());
bstrncpy(dev->pool_name, dcr->pool_name, sizeof(dev->pool_name));
bstrncpy(dev->pool_type, dcr->pool_type, sizeof(dev->pool_type));
return 1; /* reserve drive */
Mmsg(jcr->errmsg, _("3911 JobId=%u failed reserve drive %s.\n"),
jcr->JobId, dev->print_name());
queue_reserve_message(jcr);
- Dmsg2(dbglvl, "jid=%u failed: No reserve %s\n", jcr->JobId, dev->print_name());
+ Dmsg1(dbglvl, "failed: No reserve %s\n", dev->print_name());
return 0;
}