]> git.sur5r.net Git - bacula/bacula/blobdiff - bacula/src/stored/reserve.c
Change copyright as per agreement with FSFE
[bacula/bacula] / bacula / src / stored / reserve.c
index 3223d884fc055533805d4240a5f638c4862909d4..aca0cc4f94bd0ac7dba3bc07207227096b839d74 100644 (file)
@@ -1,39 +1,28 @@
 /*
-   Bacula® - The Network Backup Solution
-
-   Copyright (C) 2000-2009 Free Software Foundation Europe e.V.
-
-   The main author of Bacula is Kern Sibbald, with contributions from
-   many others, a complete list can be found in the file AUTHORS.
-   This program is Free Software; you can redistribute it and/or
-   modify it under the terms of version three of the GNU Affero General Public
-   License as published by the Free Software Foundation and included
-   in the file LICENSE.
-
-   This program is distributed in the hope that it will be useful, but
-   WITHOUT ANY WARRANTY; without even the implied warranty of
-   MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
-   General Public License for more details.
-
-   You should have received a copy of the GNU Affero General Public License
-   along with this program; if not, write to the Free Software
-   Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA
-   02110-1301, USA.
-
-   Bacula® is a registered trademark of Kern Sibbald.
-   The licensor of Bacula is the Free Software Foundation Europe
-   (FSFE), Fiduciary Program, Sumatrastrasse 25, 8006 Zürich,
-   Switzerland, email:ftf@fsfeurope.org.
+   Bacula(R) - The Network Backup Solution
+
+   Copyright (C) 2000-2016 Kern Sibbald
+
+   The original author of Bacula is Kern Sibbald, with contributions
+   from many others, a complete list can be found in the file AUTHORS.
+
+   You may use this file and others of this release according to the
+   license defined in the LICENSE file, which includes the Affero General
+   Public License, v3.0 ("AGPLv3") and some additional permissions and
+   terms pursuant to its AGPLv3 Section 7.
+
+   This notice must be preserved when any source code is 
+   conveyed and/or propagated.
+
+   Bacula(R) is a registered trademark of Kern Sibbald.
 */
 /*
  *   Drive reservation functions for Storage Daemon
  *
- *   Kern Sibbald, MM
+ *   Written by Kern Sibbald, MM
  *
  *   Split from job.c and acquire.c June 2005
  *
- *   Version $Id$
- *
  */
 
 #include "bacula.h"
 const int dbglvl = 150;
 
 static brwlock_t reservation_lock;
+int reservations_lock_count = 0;
 
 /* Forward referenced functions */
-static int can_reserve_drive(DCR *dcr, RCTX &rctx);
-static int reserve_device(RCTX &rctx);
-static bool reserve_device_for_read(DCR *dcr);
+static int  can_reserve_drive(DCR *dcr, RCTX &rctx);
+static bool is_vol_in_autochanger(RCTX &rctx, VOLRES *vol);
 static bool reserve_device_for_append(DCR *dcr, RCTX &rctx);
-static bool use_storage_cmd(JCR *jcr);
-static void queue_reserve_message(JCR *jcr);
+static bool reserve_device_for_read(DCR *dcr);
+static bool use_device_cmd(JCR *jcr);
+static int  reserve_device(RCTX &rctx);
 static void pop_reserve_messages(JCR *jcr);
+static void queue_reserve_message(JCR *jcr);
 //void switch_device(DCR *dcr, DEVICE *dev);
 
 /* Requests from the Director daemon */
 static char use_storage[]  = "use storage=%127s media_type=%127s "
-   "pool_name=%127s pool_type=%127s append=%d copy=%d stripe=%d\n";
+    "pool_name=%127s pool_type=%127s append=%d copy=%d stripe=%d\n";
 static char use_device[]  = "use device=%127s\n";
 
 /* Responses sent to Director daemon */
 static char OK_device[] = "3000 OK use device device=%s\n";
-static char NO_device[] = "3924 Device \"%s\" not in SD Device resources.\n";
+static char NO_device[] = "3924 Device \"%s\" not in SD Device"
+     " resources or no matching Media Type.\n";
 static char BAD_use[]   = "3913 Bad use command: %s\n";
 
-bool use_cmd(JCR *jcr) 
-{
-   /*
-    * Get the device, media, and pool information
-    */
-   if (!use_storage_cmd(jcr)) {
-      set_jcr_job_status(jcr, JS_ErrorTerminated);
-      memset(jcr->sd_auth_key, 0, strlen(jcr->sd_auth_key));
-      return false;
-   }
-   return true;
-}
-
 /*
  * This allows a given thread to recursively call lock_reservations.
  *   It must, of course, call unlock_... the same number of times.
@@ -92,13 +71,6 @@ void init_reservations_lock()
    init_vol_list_lock();
 }
 
-void term_reservations_lock()
-{
-   rwl_destroy(&reservation_lock);
-   term_vol_list_lock();
-}
-
-int reservations_lock_count = 0;
 
 /* This applies to a drive and to Volumes */
 void _lock_reservations(const char *file, int line)
@@ -123,11 +95,10 @@ void _unlock_reservations()
    }
 }
 
-void DCR::set_reserved()
+void term_reservations_lock()
 {
-   m_reserved = true;
-   Dmsg2(dbglvl, "Inc reserve=%d dev=%s\n", dev->num_reserved(), dev->print_name());
-   dev->inc_reserved();
+   rwl_destroy(&reservation_lock);
+   term_vol_list_lock();
 }
 
 void DCR::clear_reserved()
@@ -139,19 +110,37 @@ void DCR::clear_reserved()
    }
 }
 
-/* 
+void DCR::set_reserved_for_append()
+{
+   m_reserved = true;
+   dev->set_append_reserve();
+   Dmsg2(dbglvl, "Inc reserve=%d dev=%s\n", dev->num_reserved(), dev->print_name());
+   dev->inc_reserved();
+}
+
+void DCR::set_reserved_for_read()
+{
+   m_reserved = true;
+   dev->set_read_reserve();
+   Dmsg2(dbglvl, "Inc reserve=%d dev=%s\n", dev->num_reserved(), dev->print_name());
+   dev->inc_reserved();
+}
+
+/*
  * Remove any reservation from a drive and tell the system
  *  that the volume is unused at least by us.
  */
-void DCR::unreserve_device()
+void DCR::unreserve_device(bool locked)
 {
-   dev->dlock();
-   lock_volumes();
+   if (!locked) {
+      dev->Lock();
+   }
    if (is_reserved()) {
       clear_reserved();
       reserved_volume = false;
       /* If we set read mode in reserving, remove it */
       if (dev->can_read()) {
+         remove_read_volume(jcr, this->VolumeName);
          dev->clear_read();
       }
       if (dev->num_writers < 0) {
@@ -159,11 +148,26 @@ void DCR::unreserve_device()
          dev->num_writers = 0;
       }
       if (dev->num_reserved() == 0 && dev->num_writers == 0) {
+         generate_plugin_event(jcr, bsdEventDeviceClose, this);
          volume_unused(this);
       }
    }
-   unlock_volumes();
-   dev->dunlock();
+   if (!locked) {
+      dev->Unlock();
+   }
+}
+
+bool use_cmd(JCR *jcr)
+{
+   /*
+    * Get the device, media, and pool information
+    */
+   if (!use_device_cmd(jcr)) {
+      jcr->setJobStatus(JS_ErrorTerminated);
+      memset(jcr->sd_auth_key, 0, strlen(jcr->sd_auth_key));
+      return false;
+   }
+   return true;
 }
 
 /*
@@ -177,13 +181,13 @@ void DCR::unreserve_device()
  *  use device=bbb
  *
  */
-static bool use_storage_cmd(JCR *jcr)
+static bool use_device_cmd(JCR *jcr)
 {
    POOL_MEM store_name, dev_name, media_type, pool_name, pool_type;
    BSOCK *dir = jcr->dir_bsock;
-   int append;
-   bool ok;       
-   int Copy, Stripe;
+   int32_t append;
+   bool ok;
+   int32_t Copy, Stripe;
    DIRSTORE *store;
    RCTX rctx;
    alist *dirstore;
@@ -195,11 +199,11 @@ static bool use_storage_cmd(JCR *jcr)
     *   use_device for each device that it wants to use.
     */
    dirstore = New(alist(10, not_owned_by_alist));
-   jcr->reserve_msgs = New(alist(10, not_owned_by_alist));  
+   jcr->reserve_msgs = New(alist(10, not_owned_by_alist));
    do {
       Dmsg1(dbglvl, "<dird: %s", dir->msg);
-      ok = sscanf(dir->msg, use_storage, store_name.c_str(), 
-                  media_type.c_str(), pool_name.c_str(), 
+      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;
       if (!ok) {
          break;
@@ -236,42 +240,50 @@ static bool use_storage_cmd(JCR *jcr)
       }
    }  while (ok && dir->recv() >= 0);
 
-   /* Developer debug code */
+#ifdef xxxx
+   /* Developers debug code */
    char *device_name;
    if (debug_level >= dbglvl) {
       foreach_alist(store, dirstore) {
-         Dmsg5(dbglvl, "Storage=%s media_type=%s pool=%s pool_type=%s append=%d\n", 
-            store->name, store->media_type, store->pool_name, 
+         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) {
             Dmsg1(dbglvl, "     Device=%s\n", device_name);
          }
       }
    }
+#endif
 
    init_jcr_device_wait_timers(jcr);
-   jcr->dcr = new_dcr(jcr, NULL, NULL);         /* get a dcr */
+   jcr->dcr = new_dcr(jcr, NULL, NULL, !rctx.append);   /* get a dcr */
    if (!jcr->dcr) {
       BSOCK *dir = jcr->dir_bsock;
       dir->fsend(_("3939 Could not get dcr\n"));
       Dmsg1(dbglvl, ">dird: %s", dir->msg);
       ok = false;
    }
-   /*                    
+   /*
     * At this point, we have a list of all the Director's Storage
     *  resources indicated for this Job, which include Pool, PoolType,
-    *  storage name, and Media type.     
+    *  storage name, and Media type.
     * Then for each of the Storage resources, we have a list of
     *  device names that were given.
     *
     * Wiffle through them and find one that can do the backup.
     */
    if (ok) {
-      int wait_for_device_retries = 0;  
+      int wait_for_device_retries = 0;
       int repeat = 0;
       bool fail = false;
       rctx.notify_dir = true;
 
+      /* Put new dcr in proper location */
+      if (rctx.append) {
+         rctx.jcr->dcr = jcr->dcr;
+      } else {
+         rctx.jcr->read_dcr = jcr->dcr;
+      }
       lock_reservations();
       for ( ; !fail && !job_canceled(jcr); ) {
          pop_reserve_messages(jcr);
@@ -287,7 +299,7 @@ static bool use_storage_cmd(JCR *jcr)
              */
             rctx.num_writers = 20000000;   /* start with impossible number */
             rctx.low_use_drive = NULL;
-            rctx.PreferMountedVols = false;                
+            rctx.PreferMountedVols = false;
             rctx.exact_match = false;
             rctx.autochanger_only = true;
             if ((ok = find_suitable_device_for_job(jcr, rctx))) {
@@ -329,7 +341,7 @@ static bool use_storage_cmd(JCR *jcr)
          }
          /* Keep reservations locked *except* during wait_for_device() */
          unlock_reservations();
-         /*     
+         /*
           * The idea of looping on repeat a few times it to ensure
           * that if there is some subtle timing problem between two
           * jobs, we will simply try again, and most likely succeed.
@@ -338,15 +350,16 @@ static bool use_storage_cmd(JCR *jcr)
           */
          if (repeat++ > 1) {              /* try algorithm 3 times */
             bmicrosleep(30, 0);           /* wait a bit */
-            Dmsg0(dbglvl, "repeat reserve algorithm\n");
-         } else if (!rctx.suitable_device || !wait_for_device(jcr, wait_for_device_retries)) {
-            Dmsg0(dbglvl, "Fail. !suitable_device || !wait_for_device\n");
+            Dmsg1(100, "repeat reserve algorithm JobId=%d\n", jcr->JobId);
+         } else if (!rctx.suitable_device || !wait_for_any_device(jcr, wait_for_device_retries)) {
+            Dmsg0(100, "Fail. !suitable_device || !wait_for_device\n");
             fail = true;
-         }   
+         }
          lock_reservations();
          dir->signal(BNET_HEARTBEAT);  /* Inform Dir that we are alive */
       }
       unlock_reservations();
+
       if (!ok) {
          /*
           * If we get here, there are no suitable devices available, which
@@ -355,7 +368,7 @@ static bool use_storage_cmd(JCR *jcr)
           */
          unbash_spaces(dir->msg);
          pm_strcpy(jcr->errmsg, dir->msg);
-         Jmsg(jcr, M_FATAL, 0, _("Device reservation failed for JobId=%d: %s\n"), 
+         Jmsg(jcr, M_FATAL, 0, _("Device reservation failed for JobId=%d: %s\n"),
               jcr->JobId, jcr->errmsg);
          dir->fsend(NO_device, dev_name.c_str());
 
@@ -373,33 +386,12 @@ static bool use_storage_cmd(JCR *jcr)
    return ok;
 }
 
-
-/*
- * Walk through the autochanger resources and check if
- *  the volume is in one of them.
- * 
- * Returns:  true  if volume is in device
- *           false otherwise
- */
-static bool is_vol_in_autochanger(RCTX &rctx, VOLRES *vol)
-{
-   AUTOCHANGER *changer = vol->dev->device->changer_res;
-
-   /* Find resource, and make sure we were able to open it */
-   if (strcmp(rctx.device_name, changer->hdr.name) == 0) {
-      Dmsg1(dbglvl, "Found changer device %s\n", vol->dev->device->hdr.name);
-      return true;
-   }  
-   Dmsg1(dbglvl, "Incorrect changer device %s\n", changer->hdr.name);
-   return false;
-}
-
 /*
  * Search for a device suitable for this job.
- * Note, this routine sets sets rctx.suitable_device if any 
+ * Note, this routine sets sets rctx.suitable_device if any
  *   device exists within the SD.  The device may not be actually
  *   useable.
- * It also returns if it finds a useable device.  
+ * It also returns if it finds a useable device.
  */
 bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
 {
@@ -418,7 +410,7 @@ bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
          rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
          rctx.autochanger_only, rctx.any_drive);
 
-   /* 
+   /*
     * If the appropriate conditions of this if are met, namely that
     *  we are appending and the user wants mounted drive (or we
     *  force try a mounted drive because they are all busy), we
@@ -451,9 +443,13 @@ bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
                rctx.device_name = device_name;
                rctx.device = vol->dev->device;
 
+               if (vol->dev->read_only) {
+                  continue;
+               }
                if (vol->dev->is_autochanger()) {
                   Dmsg1(dbglvl, "vol=%s is in changer\n", vol->vol_name);
-                  if (!is_vol_in_autochanger(rctx, vol)) {
+                  if (!is_vol_in_autochanger(rctx, vol) || !vol->dev->autoselect ||
+                     !vol->dev->enabled) {
                      continue;
                   }
                } else if (strcmp(device_name, vol->dev->device->hdr.name) != 0) {
@@ -497,8 +493,8 @@ bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
       return true;
    }
 
-   /* 
-    * No reserved volume we can use, so now search for an available device.  
+   /*
+    * No reserved volume we can use, so now search for an available device.
     *
     * For each storage device that the user specified, we
     *  search and see if there is a resource for that device.
@@ -508,7 +504,7 @@ bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
       foreach_alist(device_name, store->device) {
          int stat;
          rctx.device_name = device_name;
-         stat = search_res_for_device(rctx); 
+         stat = search_res_for_device(rctx);
          if (stat == 1) {             /* found available device */
             Dmsg1(dbglvl, "available device found=%s\n", device_name);
             ok = true;
@@ -536,7 +532,7 @@ bool find_suitable_device_for_job(JCR *jcr, RCTX &rctx)
  * Search for a particular storage device with particular storage
  *  characteristics (MediaType).
  */
-int search_res_for_device(RCTX &rctx) 
+int search_res_for_device(RCTX &rctx)
 {
    AUTOCHANGER *changer;
    int stat;
@@ -550,6 +546,9 @@ int search_res_for_device(RCTX &rctx)
          /* Try each device in this AutoChanger */
          foreach_alist(rctx.device, changer->device) {
             Dmsg1(dbglvl, "Try changer device %s\n", rctx.device->hdr.name);
+            if (rctx.store->append && rctx.device->read_only) {
+               continue;
+            }
             if (!rctx.device->autoselect) {
                Dmsg1(100, "Device %s not autoselect skipped.\n",
                rctx.device->hdr.name);
@@ -560,11 +559,11 @@ int search_res_for_device(RCTX &rctx)
                continue;
             }
             /* Debug code */
-            if (rctx.store->append == SD_APPEND) {
-               Dmsg2(dbglvl, "Device %s reserved=%d for append.\n", 
+            if (rctx.store->append) {
+               Dmsg2(dbglvl, "Device %s reserved=%d for append.\n",
                   rctx.device->hdr.name, rctx.jcr->dcr->dev->num_reserved());
             } else {
-               Dmsg2(dbglvl, "Device %s reserved=%d for read.\n", 
+               Dmsg2(dbglvl, "Device %s reserved=%d for read.\n",
                   rctx.device->hdr.name, rctx.jcr->read_dcr->dev->num_reserved());
             }
             return stat;
@@ -583,11 +582,11 @@ int search_res_for_device(RCTX &rctx)
                continue;
             }
             /* Debug code */
-            if (rctx.store->append == SD_APPEND) {
-               Dmsg2(dbglvl, "Device %s reserved=%d for append.\n", 
+            if (rctx.store->append) {
+               Dmsg2(dbglvl, "Device %s reserved=%d for append.\n",
                   rctx.device->hdr.name, rctx.jcr->dcr->dev->num_reserved());
             } else {
-               Dmsg2(dbglvl, "Device %s reserved=%d for read.\n", 
+               Dmsg2(dbglvl, "Device %s reserved=%d for read.\n",
                   rctx.device->hdr.name, rctx.jcr->read_dcr->dev->num_reserved());
             }
             return stat;
@@ -597,6 +596,31 @@ int search_res_for_device(RCTX &rctx)
    return -1;                    /* nothing found */
 }
 
+/*
+ * Walk through the autochanger resources and check if
+ *  the volume is in one of them.
+ *
+ * Returns:  true  if volume is in device
+ *           false otherwise
+ */
+static bool is_vol_in_autochanger(RCTX &rctx, VOLRES *vol)
+{
+   AUTOCHANGER *changer = vol->dev->device->changer_res;
+
+   if (!changer) {
+      return false;
+   }
+
+   /* Find resource, and make sure we were able to open it */
+   if (strcmp(rctx.device_name, changer->hdr.name) == 0) {
+      Dmsg1(dbglvl, "Found changer device %s\n", vol->dev->device->hdr.name);
+      return true;
+   }
+   Dmsg1(dbglvl, "Incorrect changer device %s\n", changer->hdr.name);
+   return false;
+}
+
+
 /*
  *  Try to reserve a specific device.
  *
@@ -632,14 +656,19 @@ static int reserve_device(RCTX &rctx)
               rctx.device_name);
       }
       return -1;  /* no use waiting */
-   }  
+   } else if (!rctx.device->dev->enabled) {
+      Jmsg(rctx.jcr, M_WARNING, 0, _("\n"
+           "     Device \"%s\" requested by DIR is disabled.\n"),
+              rctx.device_name);
+      return -1;  /* no use waiting */
+   }
 
    rctx.suitable_device = true;
    Dmsg1(dbglvl, "try reserve %s\n", rctx.device->hdr.name);
    if (rctx.store->append) {
-      dcr = new_dcr(rctx.jcr, rctx.jcr->dcr, rctx.device->dev);
+      dcr = new_dcr(rctx.jcr, rctx.jcr->dcr, rctx.device->dev, SD_APPEND);
    } else {
-      dcr = new_dcr(rctx.jcr, rctx.jcr->read_dcr, rctx.device->dev);
+      dcr = new_dcr(rctx.jcr, rctx.jcr->read_dcr, rctx.device->dev, SD_READ);
    }
    if (!dcr) {
       BSOCK *dir = rctx.jcr->dir_bsock;
@@ -651,8 +680,8 @@ static int reserve_device(RCTX &rctx)
    bstrncpy(dcr->pool_type, rctx.store->pool_type, name_len);
    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) {
-      Dmsg2(dbglvl, "call reserve for append: have_vol=%d vol=%s\n", rctx.have_volume, rctx.VolumeName);                                   
+   if (rctx.store->append) {
+      Dmsg2(dbglvl, "call reserve for append: have_vol=%d vol=%s\n", rctx.have_volume, rctx.VolumeName);
       ok = reserve_device_for_append(dcr, rctx);
       if (!ok) {
          goto bail_out;
@@ -662,10 +691,10 @@ static int reserve_device(RCTX &rctx)
       Dmsg5(dbglvl, "Reserved=%d dev_name=%s mediatype=%s pool=%s ok=%d\n",
                dcr->dev->num_reserved(),
                dcr->dev_name, dcr->media_type, dcr->pool_name, ok);
-      Dmsg3(dbglvl, "Vol=%s num_writers=%d, have_vol=%d\n", 
+      Dmsg3(dbglvl, "Vol=%s num_writers=%d, have_vol=%d\n",
          rctx.VolumeName, dcr->dev->num_writers, rctx.have_volume);
       if (rctx.have_volume) {
-         Dmsg0(dbglvl, "Call reserve_volume\n");
+         Dmsg0(dbglvl, "Call reserve_volume for append.\n");
          if (reserve_volume(dcr, rctx.VolumeName)) {
             Dmsg1(dbglvl, "Reserved vol=%s\n", rctx.VolumeName);
          } else {
@@ -680,6 +709,7 @@ static int reserve_device(RCTX &rctx)
             rctx.have_volume = true;
             Dmsg1(dbglvl, "looking for Volume=%s\n", rctx.VolumeName);
          } else {
+            dcr->dev->clear_wait();
             Dmsg0(dbglvl, "No next volume found\n");
             rctx.have_volume = false;
             rctx.VolumeName[0] = 0;
@@ -693,7 +723,7 @@ static int reserve_device(RCTX &rctx)
             if (dcr->found_in_use() && !rctx.PreferMountedVols) {
                rctx.PreferMountedVols = true;
                if (dcr->VolumeName[0]) {
-                  dcr->unreserve_device();
+                  dcr->unreserve_device(false);
                }
                goto bail_out;
             }
@@ -703,14 +733,14 @@ static int reserve_device(RCTX &rctx)
              *  drive, and in that case, the call above to find the next
              *  volume will fail because in attempting to reserve the Volume
              *  the code will realize that we already have a tape mounted,
-             *  and it will fail.  This *should* only happen if there are 
+             *  and it will fail.  This *should* only happen if there are
              *  writers, thus the following test.  In that case, we simply
              *  bail out, and continue waiting, rather than plunging on
-             *  and hoping that the operator can resolve the problem. 
+             *  and hoping that the operator can resolve the problem.
              */
             if (dcr->dev->num_writers != 0) {
                if (dcr->VolumeName[0]) {
-                  dcr->unreserve_device();
+                  dcr->unreserve_device(false);
                }
                goto bail_out;
             }
@@ -736,6 +766,9 @@ static int reserve_device(RCTX &rctx)
       bash_spaces(dev_name);
       ok = dir->fsend(OK_device, dev_name.c_str());  /* Return real device name */
       Dmsg1(dbglvl, ">dird: %s", dir->msg);
+      if (!ok) {
+         dcr->unreserve_device(false);
+      }
    } else {
       ok = true;
    }
@@ -748,61 +781,14 @@ bail_out:
    return 0;
 }
 
-/*
- * We "reserve" the drive by setting the ST_READ bit. No one else
- *  should touch the drive until that is cleared.
- *  This allows the DIR to "reserve" the device before actually
- *  starting the job. 
- */
-static bool reserve_device_for_read(DCR *dcr)
-{
-   DEVICE *dev = dcr->dev;
-   JCR *jcr = dcr->jcr;
-   bool ok = false;
-
-   ASSERT(dcr);
-   if (job_canceled(jcr)) {
-      return false;
-   }
-
-   dev->dlock();  
-
-   if (dev->is_device_unmounted()) {             
-      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);
-      goto bail_out;
-   }
-
-   if (dev->is_busy()) {
-      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->num_reserved());
-      Mmsg(jcr->errmsg, _("3602 JobId=%u device %s is busy (already reading/writing).\n"),
-            jcr->JobId, dev->print_name());
-      queue_reserve_message(jcr);
-      goto bail_out;
-   }
-
-   dev->clear_append();
-   dev->set_read();
-   ok = true;
-   dcr->set_reserved();
-
-bail_out:
-   dev->dunlock();
-   return ok;
-}
-
 
 /*
  * We reserve the device for appending by incrementing
  *  num_reserved(). We do virtually all the same work that
  *  is done in acquire_device_for_append(), but we do
  *  not attempt to mount the device. This routine allows
- *  the DIR to reserve multiple devices before *really* 
- *  starting the job. It also permits the SD to refuse 
+ *  the DIR to reserve multiple devices before *really*
+ *  starting the job. It also permits the SD to refuse
  *  certain devices (not up, ...).
  *
  * Note, in reserving a device, if the device is for the
@@ -817,32 +803,32 @@ static bool reserve_device_for_append(DCR *dcr, RCTX &rctx)
    DEVICE *dev = dcr->dev;
    bool ok = false;
 
-   ASSERT(dcr);
+   ASSERT2(dcr, "No dcr in reserve_device_for_append!");
    if (job_canceled(jcr)) {
       return false;
    }
 
-   dev->dlock();
+   dev->Lock();
 
-   /* If device is being read, we cannot write it */
-   if (dev->can_read()) {
-      Mmsg(jcr->errmsg, _("3603 JobId=%u device %s is busy reading.\n"), 
-         jcr->JobId, dev->print_name());
-      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+   /* If device is being read or reserved for read, we cannot write it */
+   if (dev->can_read() || dev->is_reserved_for_read()) {
+      Mmsg(jcr->errmsg, _("3603 JobId=%u %s device %s is busy reading.\n"),
+         jcr->JobId, dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
+      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
       goto bail_out;
    }
 
    /* If device is unmounted, we are out of luck */
    if (dev->is_device_unmounted()) {
-      Mmsg(jcr->errmsg, _("3604 JobId=%u device %s is BLOCKED due to user unmount.\n"), 
-         jcr->JobId, dev->print_name());
-      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+      Mmsg(jcr->errmsg, _("3604 JobId=%u %s device %s is BLOCKED due to user unmount.\n"),
+         jcr->JobId, dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
+      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
       goto bail_out;
    }
 
-   Dmsg1(dbglvl, "reserve_append device is %s\n", dev->print_name());
+   Dmsg2(dbglvl, "reserve_append %s device is %s\n", dev->print_type(), dev->print_name());
 
    /* Now do detailed tests ... */
    if (can_reserve_drive(dcr, rctx) != 1) {
@@ -850,38 +836,73 @@ static bool reserve_device_for_append(DCR *dcr, RCTX &rctx)
       goto bail_out;
    }
 
-   dcr->set_reserved();
+   /* Note: on failure this returns jcr->errmsg properly edited */
+   if (generate_plugin_event(jcr, bsdEventDeviceTryOpen, dcr) != bRC_OK) {
+      queue_reserve_message(jcr);
+      goto bail_out;
+   }
+   dcr->set_reserved_for_append();
    ok = true;
 
 bail_out:
-   dev->dunlock();
+   dev->Unlock();
    return ok;
 }
 
-static int is_pool_ok(DCR *dcr)
+/*
+ * We "reserve" the drive by setting the ST_READ bit. No one else
+ *  should touch the drive until that is cleared.
+ *  This allows the DIR to "reserve" the device before actually
+ *  starting the job.
+ */
+static bool reserve_device_for_read(DCR *dcr)
 {
    DEVICE *dev = dcr->dev;
    JCR *jcr = dcr->jcr;
+   bool ok = false;
 
-   /* Now check if we want the same Pool and pool type */
-   if (strcmp(dev->pool_name, dcr->pool_name) == 0 &&
-       strcmp(dev->pool_type, dcr->pool_type) == 0) {
-      /* OK, compatible device */
-      Dmsg1(dbglvl, "OK dev: %s num_writers=0, reserved, pool matches\n", dev->print_name());
-      return 1;
-   } else {
-      /* Drive Pool not suitable for us */
-      Mmsg(jcr->errmsg, _(
-"3608 JobId=%u wants Pool=\"%s\" but have Pool=\"%s\" nreserve=%d on drive %s.\n"), 
-            (uint32_t)jcr->JobId, dcr->pool_name, dev->pool_name,
-            dev->num_reserved(), dev->print_name());
-      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+   ASSERT2(dcr, "No dcr in reserve_device_for_read!");
+   if (job_canceled(jcr)) {
+      return false;
+   }
+
+   dev->Lock();
+
+   if (dev->is_device_unmounted()) {
+      Mmsg(jcr->errmsg, _("3601 JobId=%u %s device %s is BLOCKED due to user unmount.\n"),
+           jcr->JobId, dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
+      Dmsg1(dbglvl, "Device %s is BLOCKED due to user unmount.\n", dev->print_name());
+      goto bail_out;
    }
-   return 0;
+
+   if (dev->is_busy()) {
+      Mmsg(jcr->errmsg, _("3602 JobId=%u %s device %s is busy (already reading/writing)."
+            " read=%d, writers=%d reserved=%d\n"),
+            jcr->JobId, dev->print_type(), dev->print_name(),
+            dev->state & ST_READ?1:0, dev->num_writers, dev->num_reserved());
+      queue_reserve_message(jcr);
+      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->num_reserved());
+      goto bail_out;
+   }
+
+   /* Note: on failure this returns jcr->errmsg properly edited */
+   if (generate_plugin_event(jcr, bsdEventDeviceTryOpen, dcr) != bRC_OK) {
+      queue_reserve_message(jcr);
+      goto bail_out;
+   }
+   dev->clear_append();
+   dcr->set_reserved_for_read();
+   ok = true;
+
+bail_out:
+   dev->Unlock();
+   return ok;
 }
 
-static bool is_max_jobs_ok(DCR *dcr) 
+static bool is_max_jobs_ok(DCR *dcr)
 {
    DEVICE *dev = dcr->dev;
    JCR *jcr = dcr->jcr;
@@ -892,36 +913,63 @@ static bool is_max_jobs_ok(DCR *dcr)
          dcr->VolCatInfo.VolCatStatus,
          dcr->VolumeName);
    /* Limit max concurrent jobs on this drive */
-   if (dev->max_concurrent_jobs > 0 && dev->max_concurrent_jobs <= 
+   if (dev->max_concurrent_jobs > 0 && dev->max_concurrent_jobs <=
               (uint32_t)(dev->num_writers + dev->num_reserved())) {
       /* Max Concurrent Jobs depassed or already reserved */
-      Mmsg(jcr->errmsg, _("3609 JobId=%u Max concurrent jobs exceeded on drive %s.\n"), 
-            (uint32_t)jcr->JobId, dev->print_name());
-      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+      Mmsg(jcr->errmsg, _("3609 JobId=%u Max concurrent jobs=%d exceeded on %s device %s.\n"),
+            (uint32_t)jcr->JobId, dev->max_concurrent_jobs,
+             dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
+      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
       return false;
    }
    if (strcmp(dcr->VolCatInfo.VolCatStatus, "Recycle") == 0) {
       return true;
    }
+
    if (dcr->VolCatInfo.VolCatMaxJobs > 0 && dcr->VolCatInfo.VolCatMaxJobs <=
         (dcr->VolCatInfo.VolCatJobs + dev->num_reserved())) {
       /* Max Job Vols depassed or already reserved */
-      Mmsg(jcr->errmsg, _("3610 JobId=%u Volume max jobs exceeded on drive %s.\n"), 
-            (uint32_t)jcr->JobId, dev->print_name());
-      Dmsg1(dbglvl, "reserve dev failed: %s", jcr->errmsg);
+      Mmsg(jcr->errmsg, _("3611 JobId=%u Volume max jobs=%d exceeded on %s device %s.\n"),
+            (uint32_t)jcr->JobId, dcr->VolCatInfo.VolCatMaxJobs,
+            dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
+      Dmsg1(dbglvl, "reserve dev failed: %s", jcr->errmsg);
       return false;                /* wait */
    }
    return true;
 }
 
+
+static int is_pool_ok(DCR *dcr)
+{
+   DEVICE *dev = dcr->dev;
+   JCR *jcr = dcr->jcr;
+
+   /* Now check if we want the same Pool and pool type */
+   if (strcmp(dev->pool_name, dcr->pool_name) == 0 &&
+       strcmp(dev->pool_type, dcr->pool_type) == 0) {
+      /* OK, compatible device */
+      Dmsg1(dbglvl, "OK dev: %s num_writers=0, reserved, pool matches\n", dev->print_name());
+      return 1;
+   } else {
+      /* Drive Pool not suitable for us */
+      Mmsg(jcr->errmsg, _(
+"3608 JobId=%u wants Pool=\"%s\" but have Pool=\"%s\" nreserve=%d on %s device %s.\n"),
+            (uint32_t)jcr->JobId, dcr->pool_name, dev->pool_name,
+            dev->num_reserved(), dev->print_type(), dev->print_name());
+      Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+      queue_reserve_message(jcr);
+   }
+   return 0;
+}
+
 /*
  * Returns: 1 if drive can be reserved
  *          0 if we should wait
  *         -1 on error or impossibility
  */
-static int can_reserve_drive(DCR *dcr, RCTX &rctx) 
+static int can_reserve_drive(DCR *dcr, RCTX &rctx)
 {
    DEVICE *dev = dcr->dev;
    JCR *jcr = dcr->jcr;
@@ -938,10 +986,10 @@ static int can_reserve_drive(DCR *dcr, RCTX &rctx)
    /* setting any_drive overrides PreferMountedVols flag */
    if (!rctx.any_drive) {
       /*
-       * When PreferMountedVols is set, we keep track of the 
+       * When PreferMountedVols is set, we keep track of the
        *  drive in use that has the least number of writers, then if
-       *  no unmounted drive is found, we try that drive. This   
-       *  helps spread the load to the least used drives.  
+       *  no unmounted drive is found, we try that drive. This
+       *  helps spread the load to the least used drives.
        */
       if (rctx.try_low_use_drive && dev == rctx.low_use_drive) {
          Dmsg2(dbglvl, "OK dev=%s == low_drive=%s.\n",
@@ -954,24 +1002,24 @@ static int can_reserve_drive(DCR *dcr, RCTX &rctx)
          if ((dev->num_writers + dev->num_reserved()) < rctx.num_writers) {
             rctx.num_writers = dev->num_writers + dev->num_reserved();
             rctx.low_use_drive = dev;
-            Dmsg2(dbglvl, "set low use drive=%s num_writers=%d\n", 
+            Dmsg2(dbglvl, "set low use drive=%s num_writers=%d\n",
                dev->print_name(), rctx.num_writers);
          } else {
             Dmsg1(dbglvl, "not low use num_writers=%d\n", dev->num_writers+dev->num_reserved());
          }
-         Mmsg(jcr->errmsg, _("3605 JobId=%u wants free drive but device %s is busy.\n"), 
-            jcr->JobId, dev->print_name());
-         Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+         Mmsg(jcr->errmsg, _("3605 JobId=%u wants free drive but %s device %s is busy.\n"),
+            jcr->JobId, dev->print_type(), dev->print_name());
          queue_reserve_message(jcr);
+         Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
          return 0;
       }
 
       /* Check for prefer mounted volumes */
       if (rctx.PreferMountedVols && !dev->vol && dev->is_tape()) {
-         Mmsg(jcr->errmsg, _("3606 JobId=%u prefers mounted drives, but drive %s has no Volume.\n"), 
-            jcr->JobId, dev->print_name());
-         Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
+         Mmsg(jcr->errmsg, _("3606 JobId=%u prefers mounted drives, but %s device %s has no Volume.\n"),
+            jcr->JobId, dev->print_type(), dev->print_name());
          queue_reserve_message(jcr);
+         Dmsg1(dbglvl, "Failed: %s", jcr->errmsg);
          return 0;                 /* No volume mounted */
       }
 
@@ -985,14 +1033,14 @@ static int can_reserve_drive(DCR *dcr, RCTX &rctx)
                rctx.PreferMountedVols, rctx.exact_match, rctx.suitable_device,
                rctx.autochanger_only, rctx.any_drive);
          Dmsg4(dbglvl, "have_vol=%d have=%s resvol=%s want=%s\n",
-                  rctx.have_volume, dev->VolHdr.VolumeName, 
+                  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);
          if (!ok) {
-            Mmsg(jcr->errmsg, _("3607 JobId=%u wants Vol=\"%s\" drive has Vol=\"%s\" on drive %s.\n"), 
-               jcr->JobId, rctx.VolumeName, dev->VolHdr.VolumeName, 
-               dev->print_name());
+            Mmsg(jcr->errmsg, _("3607 JobId=%u wants Vol=\"%s\" drive has Vol=\"%s\" on %s device %s.\n"),
+               jcr->JobId, rctx.VolumeName, dev->VolHdr.VolumeName,
+               dev->print_type(), dev->print_name());
             queue_reserve_message(jcr);
             Dmsg3(dbglvl, "not OK: dev have=%s resvol=%s want=%s\n",
                   dev->VolHdr.VolumeName, dev->vol?dev->vol->vol_name:"*none*", rctx.VolumeName);
@@ -1019,11 +1067,11 @@ static int can_reserve_drive(DCR *dcr, RCTX &rctx)
     */
    if (dev->num_writers == 0) {
       /* Now check if there are any reservations on the drive */
-      if (dev->num_reserved()) {           
+      if (dev->num_reserved()) {
          return is_pool_ok(dcr);
       } else if (dev->can_append()) {
          if (is_pool_ok(dcr)) {
-            return 1; 
+            return 1;
          } else {
             /* Changing pool, unload old tape if any in drive */
             Dmsg0(dbglvl, "OK dev: num_writers=0, not reserved, pool change, unload changer\n");
@@ -1046,28 +1094,26 @@ static int can_reserve_drive(DCR *dcr, RCTX &rctx)
       return is_pool_ok(dcr);
    } else {
       Pmsg1(000, _("Logic error!!!! JobId=%u Should not get here.\n"), (int)jcr->JobId);
-      Mmsg(jcr->errmsg, _("3910 JobId=%u Logic error!!!! drive %s Should not get here.\n"),
-            jcr->JobId, dev->print_name());
+      Mmsg(jcr->errmsg, _("3910 JobId=%u Logic error!!!! %s device %s Should not get here.\n"),
+            jcr->JobId, dev->print_type(), dev->print_name());
       queue_reserve_message(jcr);
       Jmsg0(jcr, M_FATAL, 0, _("Logic error!!!! Should not get here.\n"));
       return -1;                      /* error, should not get here */
    }
-   Mmsg(jcr->errmsg, _("3911 JobId=%u failed reserve drive %s.\n"), 
-         jcr->JobId, dev->print_name());
+   Mmsg(jcr->errmsg, _("3911 JobId=%u failed reserve %s device %s.\n"),
+         jcr->JobId, dev->print_type(), dev->print_name());
    queue_reserve_message(jcr);
    Dmsg1(dbglvl, "Failed: No reserve %s\n", dev->print_name());
    return 0;
 }
 
 
-
-
 /*
  * Queue a reservation error or failure message for this jcr
  */
 static void queue_reserve_message(JCR *jcr)
 {
-   int i;   
+   int i;
    alist *msgs;
    char *msg;
 
@@ -1090,7 +1136,7 @@ static void queue_reserve_message(JCR *jcr)
       if (strncmp(msg, jcr->errmsg, 4) == 0) {
          goto bail_out;
       }
-   }      
+   }
    /* Message unique, so insert it */
    jcr->reserve_msgs->push(bstrdup(jcr->errmsg));
 
@@ -1147,7 +1193,7 @@ bail_out:
 }
 
 /*
- * Also called from acquire.c 
+ * Also called from acquire.c
  */
 void release_reserve_messages(JCR *jcr)
 {