]> git.sur5r.net Git - bacula/bacula/blob - bacula/src/stored/askdir.c
Second half of proposed fix for bug #1227 that does not
[bacula/bacula] / bacula / src / stored / askdir.c
1 /*
2    Bacula® - The Network Backup Solution
3
4    Copyright (C) 2000-2009 Free Software Foundation Europe e.V.
5
6    The main author of Bacula is Kern Sibbald, with contributions from
7    many others, a complete list can be found in the file AUTHORS.
8    This program is Free Software; you can redistribute it and/or
9    modify it under the terms of version two of the GNU General Public
10    License as published by the Free Software Foundation and included
11    in the file LICENSE.
12
13    This program is distributed in the hope that it will be useful, but
14    WITHOUT ANY WARRANTY; without even the implied warranty of
15    MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU
16    General Public License for more details.
17
18    You should have received a copy of the GNU General Public License
19    along with this program; if not, write to the Free Software
20    Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA
21    02110-1301, USA.
22
23    Bacula® is a registered trademark of Kern Sibbald.
24    The licensor of Bacula is the Free Software Foundation Europe
25    (FSFE), Fiduciary Program, Sumatrastrasse 25, 8006 Zürich,
26    Switzerland, email:ftf@fsfeurope.org.
27 */
28 /*
29  *  Subroutines to handle Catalog reqests sent to the Director
30  *   Reqests/commands from the Director are handled in dircmd.c
31  *
32  *   Kern Sibbald, December 2000
33  *
34  *   Version $Id$
35  */
36
37 #include "bacula.h"                   /* pull in global headers */
38 #include "stored.h"                   /* pull in Storage Deamon headers */
39
40 /* Requests sent to the Director */
41 static char Find_media[]   = "CatReq Job=%s FindMedia=%d pool_name=%s media_type=%s\n";
42 static char Get_Vol_Info[] = "CatReq Job=%s GetVolInfo VolName=%s write=%d\n";
43 static char Update_media[] = "CatReq Job=%s UpdateMedia VolName=%s"
44    " VolJobs=%u VolFiles=%u VolBlocks=%u VolBytes=%s VolMounts=%u"
45    " VolErrors=%u VolWrites=%u MaxVolBytes=%s EndTime=%s VolStatus=%s"
46    " Slot=%d relabel=%d InChanger=%d VolReadTime=%s VolWriteTime=%s"
47    " VolFirstWritten=%s VolParts=%u\n";
48 static char Create_job_media[] = "CatReq Job=%s CreateJobMedia"
49    " FirstIndex=%u LastIndex=%u StartFile=%u EndFile=%u"
50    " StartBlock=%u EndBlock=%u Copy=%d Strip=%d MediaId=%s\n";
51 static char FileAttributes[] = "UpdCat Job=%s FileAttributes ";
52 static char Job_status[]     = "Status Job=%s JobStatus=%d\n";
53
54 /* Responses received from the Director */
55 static char OK_media[] = "1000 OK VolName=%127s VolJobs=%u VolFiles=%lu"
56    " VolBlocks=%lu VolBytes=%lld VolMounts=%lu VolErrors=%lu VolWrites=%lu"
57    " MaxVolBytes=%lld VolCapacityBytes=%lld VolStatus=%20s"
58    " Slot=%ld MaxVolJobs=%lu MaxVolFiles=%lu InChanger=%ld"
59    " VolReadTime=%lld VolWriteTime=%lld EndFile=%lu EndBlock=%lu"
60    " VolParts=%lu LabelType=%ld MediaId=%lld\n";
61
62
63 static char OK_create[] = "1000 OK CreateJobMedia\n";
64
65 static pthread_mutex_t vol_info_mutex = PTHREAD_MUTEX_INITIALIZER;
66
67 #ifdef needed
68
69 static char Device_update[] = "DevUpd Job=%s device=%s "
70    "append=%d read=%d num_writers=%d "
71    "open=%d labeled=%d offline=%d "
72    "reserved=%d max_writers=%d "
73    "autoselect=%d autochanger=%d "
74    "changer_name=%s media_type=%s volume_name=%s\n";
75
76
77 /* Send update information about a device to Director */
78 bool dir_update_device(JCR *jcr, DEVICE *dev)
79 {
80    BSOCK *dir = jcr->dir_bsock;
81    POOL_MEM dev_name, VolumeName, MediaType, ChangerName;
82    DEVRES *device = dev->device;
83    bool ok;
84    
85    pm_strcpy(dev_name, device->hdr.name);
86    bash_spaces(dev_name);
87    if (dev->is_labeled()) {
88       pm_strcpy(VolumeName, dev->VolHdr.VolumeName);
89    } else {
90       pm_strcpy(VolumeName, "*");
91    }
92    bash_spaces(VolumeName);
93    pm_strcpy(MediaType, device->media_type);
94    bash_spaces(MediaType);
95    if (device->changer_res) {
96       pm_strcpy(ChangerName, device->changer_res->hdr.name);
97       bash_spaces(ChangerName);
98    } else {
99       pm_strcpy(ChangerName, "*");
100    }
101    ok = dir->fsend(Device_update, 
102       jcr->Job,
103       dev_name.c_str(),
104       dev->can_append()!=0,
105       dev->can_read()!=0, dev->num_writers, 
106       dev->is_open()!=0, dev->is_labeled()!=0,
107       dev->is_offline()!=0, dev->reserved_device, 
108       dev->is_tape()?100000:1,
109       dev->autoselect, 0, 
110       ChangerName.c_str(), MediaType.c_str(), VolumeName.c_str());
111    Dmsg1(100, ">dird: %s\n", dir->msg);
112    return ok;
113 }
114
115 bool dir_update_changer(JCR *jcr, AUTOCHANGER *changer)
116 {
117    BSOCK *dir = jcr->dir_bsock;
118    POOL_MEM dev_name, MediaType;
119    DEVRES *device;
120    bool ok;
121
122    pm_strcpy(dev_name, changer->hdr.name);
123    bash_spaces(dev_name);
124    device = (DEVRES *)changer->device->first();
125    pm_strcpy(MediaType, device->media_type);
126    bash_spaces(MediaType);
127    /* This is mostly to indicate that we are here */
128    ok = dir->fsend(Device_update,
129       jcr->Job,
130       dev_name.c_str(),         /* Changer name */
131       0, 0, 0,                  /* append, read, num_writers */
132       0, 0, 0,                  /* is_open, is_labeled, offline */
133       0, 0,                     /* reserved, max_writers */
134       0,                        /* Autoselect */
135       changer->device->size(),  /* Number of devices */
136       "0",                      /* PoolId */
137       "*",                      /* ChangerName */
138       MediaType.c_str(),        /* MediaType */
139       "*");                     /* VolName */
140    Dmsg1(100, ">dird: %s\n", dir->msg);
141    return ok;
142 }
143 #endif
144
145
146 /*
147  * Send current JobStatus to Director
148  */
149 bool dir_send_job_status(JCR *jcr)
150 {
151    return jcr->dir_bsock->fsend(Job_status, jcr->Job, jcr->JobStatus);
152 }
153
154 /*
155  * Common routine for:
156  *   dir_get_volume_info()
157  * and
158  *   dir_find_next_appendable_volume()
159  *
160  *  NOTE!!! All calls to this routine must be protected by
161  *          locking vol_info_mutex before calling it so that
162  *          we don't have one thread modifying the parameters
163  *          and another reading them.
164  *
165  *  Returns: true  on success and vol info in dcr->VolCatInfo
166  *           false on failure
167  */
168 static bool do_get_volume_info(DCR *dcr)
169 {
170     JCR *jcr = dcr->jcr;
171     BSOCK *dir = jcr->dir_bsock;
172     VOLUME_CAT_INFO vol;
173     int n;
174     int32_t InChanger;
175
176     if (dir->recv() <= 0) {
177        Dmsg0(200, "getvolname error bnet_recv\n");
178        Mmsg(jcr->errmsg, _("Network error on bnet_recv in req_vol_info.\n"));
179        return false;
180     }
181     memset(&vol, 0, sizeof(vol));
182     Dmsg1(100, "<dird %s", dir->msg);
183     n = sscanf(dir->msg, OK_media, vol.VolCatName,
184                &vol.VolCatJobs, &vol.VolCatFiles,
185                &vol.VolCatBlocks, &vol.VolCatBytes,
186                &vol.VolCatMounts, &vol.VolCatErrors,
187                &vol.VolCatWrites, &vol.VolCatMaxBytes,
188                &vol.VolCatCapacityBytes, vol.VolCatStatus,
189                &vol.Slot, &vol.VolCatMaxJobs, &vol.VolCatMaxFiles,
190                &InChanger, &vol.VolReadTime, &vol.VolWriteTime,
191                &vol.EndFile, &vol.EndBlock, &vol.VolCatParts,
192                &vol.LabelType, &vol.VolMediaId);
193     if (n != 22) {
194        Dmsg3(100, "Bad response from Dir fields=%d, len=%d: %s", 
195              n, dir->msglen, dir->msg);
196        Mmsg(jcr->errmsg, _("Error getting Volume info: %s"), dir->msg);
197        return false;
198     }
199     vol.InChanger = InChanger;        /* bool in structure */
200     unbash_spaces(vol.VolCatName);
201     bstrncpy(dcr->VolumeName, vol.VolCatName, sizeof(dcr->VolumeName));
202     dcr->VolCatInfo = vol;            /* structure assignment */
203
204     Dmsg2(100, "do_reqest_vol_info return true slot=%d Volume=%s\n",
205           vol.Slot, vol.VolCatName);
206     return true;
207 }
208
209
210 /*
211  * Get Volume info for a specific volume from the Director's Database
212  *
213  * Returns: true  on success   (Director guarantees that Pool and MediaType
214  *                              are correct and VolStatus==Append or
215  *                              VolStatus==Recycle)
216  *          false on failure
217  *
218  *          Volume information returned in dcr->VolCatInfo
219  */
220 bool dir_get_volume_info(DCR *dcr, enum get_vol_info_rw writing)
221 {
222     JCR *jcr = dcr->jcr;
223     BSOCK *dir = jcr->dir_bsock;
224
225     P(vol_info_mutex);
226     bstrncpy(dcr->VolCatInfo.VolCatName, dcr->VolumeName, sizeof(dcr->VolCatInfo.VolCatName));
227     bash_spaces(dcr->VolCatInfo.VolCatName);
228     dir->fsend(Get_Vol_Info, jcr->Job, dcr->VolCatInfo.VolCatName,
229        writing==GET_VOL_INFO_FOR_WRITE?1:0);
230     Dmsg1(100, ">dird %s", dir->msg);
231     unbash_spaces(dcr->VolCatInfo.VolCatName);
232     bool ok = do_get_volume_info(dcr);
233     V(vol_info_mutex);
234     return ok;
235 }
236
237
238
239 /*
240  * Get info on the next appendable volume in the Director's database
241  *
242  * Returns: true  on success dcr->VolumeName is volume
243  *                reserve_volume() called on Volume name
244  *          false on failure dcr->VolumeName[0] == 0
245  *                also sets dcr->found_in_use if at least one 
246  *                in use volume was found.
247  *
248  *          Volume information returned in dcr
249  *
250  */
251 bool dir_find_next_appendable_volume(DCR *dcr)
252 {
253     JCR *jcr = dcr->jcr;
254     BSOCK *dir = jcr->dir_bsock;
255     bool rtn;
256     char lastVolume[MAX_NAME_LENGTH];
257
258     Dmsg2(200, "dir_find_next_appendable_volume: reserved=%d Vol=%s\n", 
259        dcr->is_reserved(), dcr->VolumeName);
260
261     /*
262      * Try the twenty oldest or most available volumes.  Note,
263      *   the most available could already be mounted on another
264      *   drive, so we continue looking for a not in use Volume.
265      */
266     lock_volumes();
267     P(vol_info_mutex);
268     dcr->clear_found_in_use();
269     lastVolume[0] = 0;
270     for (int vol_index=1;  vol_index < 20; vol_index++) {
271        bash_spaces(dcr->media_type);
272        bash_spaces(dcr->pool_name);
273        dir->fsend(Find_media, jcr->Job, vol_index, dcr->pool_name, dcr->media_type);
274        unbash_spaces(dcr->media_type);
275        unbash_spaces(dcr->pool_name);
276        Dmsg1(100, ">dird %s", dir->msg);
277        if (do_get_volume_info(dcr)) {
278           /* Give up if we get the same volume name twice */
279           if (lastVolume[0] && strcmp(lastVolume, dcr->VolumeName) == 0) {
280              Dmsg1(100, "Got same vol = %s\n", lastVolume);
281              break;
282           }
283           bstrncpy(lastVolume, dcr->VolumeName, sizeof(lastVolume));
284           if (dcr->can_i_write_volume()) {
285              Dmsg1(100, "Call reserve_volume. Vol=%s\n", dcr->VolumeName);
286              if (reserve_volume(dcr, dcr->VolumeName) == NULL) {
287                 Dmsg2(100, "Could not reserve volume %s on %s\n", dcr->VolumeName,
288                     dcr->dev->print_name());
289                 continue;
290              }
291              Dmsg1(100, "dir_find_next_appendable_volume return true. vol=%s\n",
292                 dcr->VolumeName);
293              rtn = true;
294              goto get_out;
295           } else {
296              Dmsg1(100, "Volume %s is in use.\n", dcr->VolumeName);
297              /* If volume is not usable, it is in use by someone else */
298              dcr->set_found_in_use();
299              continue;
300           }
301        }
302        Dmsg2(100, "No vol. index %d return false. dev=%s\n", vol_index,
303           dcr->dev->print_name());
304        break;
305     }
306     rtn = false;
307     dcr->VolumeName[0] = 0;
308
309 get_out:
310     V(vol_info_mutex);
311     unlock_volumes();
312     return rtn;
313 }
314
315
316 /*
317  * After writing a Volume, send the updated statistics
318  * back to the director. The information comes from the
319  * dev record.
320  */
321 bool dir_update_volume_info(DCR *dcr, bool label, bool update_LastWritten)
322 {
323    JCR *jcr = dcr->jcr;
324    BSOCK *dir = jcr->dir_bsock;
325    DEVICE *dev = dcr->dev;
326    VOLUME_CAT_INFO *vol = &dev->VolCatInfo;
327    char ed1[50], ed2[50], ed3[50], ed4[50], ed5[50], ed6[50];
328    int InChanger;
329    bool ok = false;
330    POOL_MEM VolumeName;
331
332    /* If system job, do not update catalog */
333    if (jcr->get_JobType() == JT_SYSTEM) {
334       return true;
335    }
336
337    if (vol->VolCatName[0] == 0) {
338       Jmsg0(jcr, M_FATAL, 0, _("NULL Volume name. This shouldn't happen!!!\n"));
339       Pmsg0(000, _("NULL Volume name. This shouldn't happen!!!\n"));
340       return false;
341    }
342
343    /* Lock during Volume update */
344    P(vol_info_mutex);
345    Dmsg1(100, "Update cat VolFiles=%d\n", dev->file);
346    /* Just labeled or relabeled the tape */
347    if (label) {
348       bstrncpy(vol->VolCatStatus, "Append", sizeof(vol->VolCatStatus));
349    }
350 // if (update_LastWritten) {
351       vol->VolLastWritten = time(NULL);
352 // }
353    pm_strcpy(VolumeName, vol->VolCatName);
354    bash_spaces(VolumeName);
355    InChanger = vol->InChanger;
356    dir->fsend(Update_media, jcr->Job,
357       VolumeName.c_str(), vol->VolCatJobs, vol->VolCatFiles,
358       vol->VolCatBlocks, edit_uint64(vol->VolCatBytes, ed1),
359       vol->VolCatMounts, vol->VolCatErrors,
360       vol->VolCatWrites, edit_uint64(vol->VolCatMaxBytes, ed2),
361       edit_uint64(vol->VolLastWritten, ed6), 
362       vol->VolCatStatus, vol->Slot, label,
363       InChanger,                      /* bool in structure */
364       edit_int64(vol->VolReadTime, ed3),
365       edit_int64(vol->VolWriteTime, ed4),
366       edit_uint64(vol->VolFirstWritten, ed5),
367       vol->VolCatParts);
368     Dmsg1(100, ">dird %s", dir->msg);
369
370    /* Do not lock device here because it may be locked from label */
371    if (!do_get_volume_info(dcr)) {
372       Jmsg(jcr, M_FATAL, 0, "%s", jcr->errmsg);
373       Dmsg2(100, _("Didn't get vol info vol=%s: ERR=%s"), 
374          vol->VolCatName, jcr->errmsg);
375       goto bail_out;
376    }
377    Dmsg1(420, "get_volume_info() %s", dir->msg);
378    /* Update dev Volume info in case something changed (e.g. expired) */
379    dev->VolCatInfo = dcr->VolCatInfo;
380    ok = true;
381
382 bail_out:
383    V(vol_info_mutex);
384    return ok;
385 }
386
387 /*
388  * After writing a Volume, create the JobMedia record.
389  */
390 bool dir_create_jobmedia_record(DCR *dcr, bool zero)
391 {
392    JCR *jcr = dcr->jcr;
393    BSOCK *dir = jcr->dir_bsock;
394    char ed1[50];
395
396    /* If system job, do not update catalog */
397    if (jcr->get_JobType() == JT_SYSTEM) {
398       return true;
399    }
400
401    /* Throw out records where FI is zero -- i.e. nothing done */
402    if (!zero && dcr->VolFirstIndex == 0 && 
403         (dcr->StartBlock != 0 || dcr->EndBlock != 0)) {
404       Dmsg0(100, "JobMedia FI=0 StartBlock!=0 record suppressed\n");
405       return true;
406    }
407
408    if (!dcr->WroteVol) {
409       return true;                    /* nothing written to tape */
410    }
411
412    dcr->WroteVol = false;
413    if (zero) {
414       /* Send dummy place holder to avoid purging */
415       dir->fsend(Create_job_media, jcr->Job,
416          0 , 0, 0, 0, 0, 0, 0, 0, edit_uint64(dcr->VolMediaId, ed1));
417    } else {
418       dir->fsend(Create_job_media, jcr->Job,
419          dcr->VolFirstIndex, dcr->VolLastIndex,
420          dcr->StartFile, dcr->EndFile,
421          dcr->StartBlock, dcr->EndBlock, 
422          dcr->Copy, dcr->Stripe, 
423          edit_uint64(dcr->VolMediaId, ed1));
424    }
425    Dmsg1(100, ">dird %s", dir->msg);
426    if (dir->recv() <= 0) {
427       Dmsg0(190, "create_jobmedia error bnet_recv\n");
428       Jmsg(jcr, M_FATAL, 0, _("Error creating JobMedia record: ERR=%s\n"),
429            dir->bstrerror());
430       return false;
431    }
432    Dmsg1(100, "<dird %s", dir->msg);
433    if (strcmp(dir->msg, OK_create) != 0) {
434       Dmsg1(130, "Bad response from Dir: %s\n", dir->msg);
435       Jmsg(jcr, M_FATAL, 0, _("Error creating JobMedia record: %s\n"), dir->msg);
436       return false;
437    }
438    return true;
439 }
440
441
442 /*
443  * Update File Attribute data
444  */
445 bool dir_update_file_attributes(DCR *dcr, DEV_RECORD *rec)
446 {
447    JCR *jcr = dcr->jcr;
448    BSOCK *dir = jcr->dir_bsock;
449    ser_declare;
450
451 #ifdef NO_ATTRIBUTES_TEST
452    return true;
453 #endif
454
455    dir->msg = check_pool_memory_size(dir->msg, sizeof(FileAttributes) +
456                 MAX_NAME_LENGTH + sizeof(DEV_RECORD) + rec->data_len + 1);
457    dir->msglen = bsnprintf(dir->msg, sizeof(FileAttributes) +
458                 MAX_NAME_LENGTH + 1, FileAttributes, jcr->Job);
459    ser_begin(dir->msg + dir->msglen, 0);
460    ser_uint32(rec->VolSessionId);
461    ser_uint32(rec->VolSessionTime);
462    ser_int32(rec->FileIndex);
463    ser_int32(rec->Stream);
464    ser_uint32(rec->data_len);
465    ser_bytes(rec->data, rec->data_len);
466    dir->msglen = ser_length(dir->msg);
467    Dmsg1(1800, ">dird %s\n", dir->msg);    /* Attributes */
468    return dir->send();
469 }
470
471
472 /*
473  *   Request the sysop to create an appendable volume
474  *
475  *   Entered with device blocked.
476  *   Leaves with device blocked.
477  *
478  *   Returns: true  on success (operator issues a mount command)
479  *            false on failure
480  *              Note, must create dev->errmsg on error return.
481  *
482  *    On success, dcr->VolumeName and dcr->VolCatInfo contain
483  *      information on suggested volume, but this may not be the
484  *      same as what is actually mounted.
485  *
486  *    When we return with success, the correct tape may or may not
487  *      actually be mounted. The calling routine must read it and
488  *      verify the label.
489  */
490 bool dir_ask_sysop_to_create_appendable_volume(DCR *dcr)
491 {
492    int stat = W_TIMEOUT;
493    DEVICE *dev = dcr->dev;
494    JCR *jcr = dcr->jcr;
495    bool got_vol = false;
496
497    Dmsg0(400, "enter dir_ask_sysop_to_create_appendable_volume\n");
498    ASSERT(dev->blocked());
499    for ( ;; ) {
500       if (job_canceled(jcr)) {
501          Mmsg(dev->errmsg,
502               _("Job %s canceled while waiting for mount on Storage Device \"%s\".\n"),
503               jcr->Job, dev->print_name());
504          Jmsg(jcr, M_INFO, 0, "%s", dev->errmsg);
505          return false;
506       }
507       got_vol = dir_find_next_appendable_volume(dcr);   /* get suggested volume */
508       if (got_vol) {
509          goto get_out;
510       } else {
511          if (stat == W_TIMEOUT || stat == W_MOUNT) {
512             Mmsg(dev->errmsg, _(
513 "Job %s waiting. Cannot find any appendable volumes.\n"
514 "Please use the \"label\"  command to create a new Volume for:\n"
515 "    Storage:      %s\n"
516 "    Pool:         %s\n"
517 "    Media type:   %s\n"),
518                jcr->Job,
519                dev->print_name(),
520                dcr->pool_name,
521                dcr->media_type);
522             Jmsg(jcr, M_MOUNT, 0, "%s", dev->errmsg);
523             Dmsg1(100, "%s", dev->errmsg);
524          }
525       }
526
527       set_jcr_job_status(jcr, JS_WaitMedia);
528       dir_send_job_status(jcr);
529
530       stat = wait_for_sysop(dcr);
531       Dmsg1(100, "Back from wait_for_sysop stat=%d\n", stat);
532       if (dev->poll) {
533          Dmsg1(100, "Poll timeout in create append vol on device %s\n", dev->print_name());
534          continue;
535       }
536
537       if (stat == W_TIMEOUT) {
538          if (!double_dev_wait_time(dev)) {
539             Mmsg(dev->errmsg, _("Max time exceeded waiting to mount Storage Device %s for Job %s\n"),
540                dev->print_name(), jcr->Job);
541             Jmsg(jcr, M_FATAL, 0, "%s", dev->errmsg);
542             Dmsg1(100, "Gave up waiting on device %s\n", dev->print_name());
543             return false;             /* exceeded maximum waits */
544          }
545          continue;
546       }
547       if (stat == W_ERROR) {
548          berrno be;
549          Mmsg0(dev->errmsg, _("pthread error in mount_next_volume.\n"));
550          Jmsg(jcr, M_FATAL, 0, "%s", dev->errmsg);
551          return false;
552       }
553       Dmsg1(100, "Someone woke me for device %s\n", dev->print_name());
554    }
555
556 get_out:
557    set_jcr_job_status(jcr, JS_Running);
558    dir_send_job_status(jcr);
559    Dmsg0(100, "leave dir_ask_sysop_to_mount_create_appendable_volume\n");
560    return true;
561 }
562
563 /*
564  *   Request to mount specific Volume
565  *
566  *   Entered with device blocked and dcr->VolumeName is desired
567  *      volume.
568  *   Leaves with device blocked.
569  *
570  *   Returns: true  on success (operator issues a mount command)
571  *            false on failure
572  *                  Note, must create dev->errmsg on error return.
573  *
574  */
575 bool dir_ask_sysop_to_mount_volume(DCR *dcr, int mode)
576 {
577    int stat = W_TIMEOUT;
578    DEVICE *dev = dcr->dev;
579    JCR *jcr = dcr->jcr;
580
581    Dmsg0(400, "enter dir_ask_sysop_to_mount_volume\n");
582    if (!dcr->VolumeName[0]) {
583       Mmsg0(dev->errmsg, _("Cannot request another volume: no volume name given.\n"));
584       return false;
585    }
586    ASSERT(dev->blocked());
587    for ( ;; ) {
588       if (job_canceled(jcr)) {
589          Mmsg(dev->errmsg, _("Job %s canceled while waiting for mount on Storage Device %s.\n"),
590               jcr->Job, dev->print_name());
591          return false;
592       }
593
594       if (dev->is_dvd()) {   
595          dev->unmount(0);
596       }
597       
598       /*
599        * If we are not polling, and the wait timeout or the
600        *   user explicitly did a mount, send him the message.
601        *   Otherwise skip it.
602        */
603       if (!dev->poll && (stat == W_TIMEOUT || stat == W_MOUNT)) {
604          char *msg;
605          if (mode == ST_APPEND) {
606             msg = _("Please mount Volume \"%s\" or label a new one for:\n"
607               "    Job:          %s\n"
608               "    Storage:      %s\n"
609               "    Pool:         %s\n"
610               "    Media type:   %s\n");
611          } else {
612             msg = _("Please mount Volume \"%s\" for:\n"
613               "    Job:          %s\n"
614               "    Storage:      %s\n"
615               "    Pool:         %s\n"
616               "    Media type:   %s\n");
617          }
618          Jmsg(jcr, M_MOUNT, 0, msg, 
619               dcr->VolumeName,
620               jcr->Job,
621               dev->print_name(),
622               dcr->pool_name,
623               dcr->media_type);
624          Dmsg3(400, "Mount \"%s\" on device \"%s\" for Job %s\n",
625                dcr->VolumeName, dev->print_name(), jcr->Job);
626       }
627
628       set_jcr_job_status(jcr, JS_WaitMount);
629       dir_send_job_status(jcr);
630
631       stat = wait_for_sysop(dcr);          /* wait on device */
632       Dmsg1(100, "Back from wait_for_sysop stat=%d\n", stat);
633       if (dev->poll) {
634          Dmsg1(400, "Poll timeout in mount vol on device %s\n", dev->print_name());
635          Dmsg1(400, "Blocked=%s\n", dev->print_blocked());
636          goto get_out;
637       }
638
639       if (stat == W_TIMEOUT) {
640          if (!double_dev_wait_time(dev)) {
641             Mmsg(dev->errmsg, _("Max time exceeded waiting to mount Storage Device %s for Job %s\n"),
642                dev->print_name(), jcr->Job);
643             Jmsg(jcr, M_FATAL, 0, "%s", dev->errmsg);
644             Dmsg1(400, "Gave up waiting on device %s\n", dev->print_name());
645             return false;             /* exceeded maximum waits */
646          }
647          continue;
648       }
649       if (stat == W_ERROR) {
650          berrno be;
651          Mmsg(dev->errmsg, _("pthread error in mount_volume\n"));
652          Jmsg(jcr, M_FATAL, 0, "%s", dev->errmsg);
653          return false;
654       }
655       Dmsg1(400, "Someone woke me for device %s\n", dev->print_name());
656       break;
657    }
658
659 get_out:
660    set_jcr_job_status(jcr, JS_Running);
661    dir_send_job_status(jcr);
662    Dmsg0(400, "leave dir_ask_sysop_to_mount_volume\n");
663    return true;
664 }