Bacula-users

Re: [Bacula-users] Restore Bug?

2008-10-21 06:22:24
Subject: Re: [Bacula-users] Restore Bug?
From: Thomas <thomas AT ic3s DOT de>
To: Bacula-users AT lists.sourceforge DOT net
Date: Tue, 21 Oct 2008 12:19:38 +0200
with 2.4.3 it is the same problem:


cwd is: /
$ cd etc
cwd is: /etc/
$ mark services
1 file marked.
$ done
Bootstrap records written to /var/lib/bacula/backup-dir.restore.1.bsr

The job will require the following
   Volume(s)                 Storage(s)                SD Device(s)
===========================================================================

   FUL142                    JBBB                      JBBB


Enter SQL query: select slot,inchanger from media where volumename = 'FUL142';
+------+-----------+
| slot | inchanger |
+------+-----------+
|   25 |         0 |
+------+-----------+


21-Okt 11:58 backup-dir JobId 54512: Start Restore Job 
RestoreFiles.2008-10-21_11.58.06
21-Okt 11:58 backup-dir JobId 54512: Using Device "JBBB-1"
21-Okt 11:58 backup-sd-bb JobId 54512: Invalid slot=0 defined in catalog for 
Volume "FUL142" on "JBBB-1" (/dev/nst0). Manual load may be required.
21-Okt 11:58 backup-sd-bb JobId 54512: 3307 Issuing autochanger "unload slot 
18, drive 0" command.
21-Okt 11:59 backup-sd-bb JobId 54512: Warning: acquire.c:260 Wrong Volume 
mounted on device "JBBB-1" (/dev/nst0): Wanted FUL142 have INC033
21-Okt 11:59 backup-sd-bb JobId 54512: 3301 Issuing autochanger "loaded? drive 
0" command.
21-Okt 11:59 backup-sd-bb JobId 54512: 3302 Autochanger "loaded? drive 0", 
result: nothing loaded.
21-Okt 11:59 backup-sd-bb JobId 54512: 3304 Issuing autochanger "load slot 25, 
drive 0" command.
21-Okt 11:59 backup-sd-bb JobId 54512: 3305 Autochanger "load slot 25, drive 
0", status is OK.
21-Okt 11:59 backup-sd-bb JobId 54512: 3307 Issuing autochanger "unload slot 
25, drive 0" command.
21-Okt 12:00 backup-sd-bb JobId 54512: Warning: acquire.c:260 Wrong Volume 
mounted on device "JBBB-1" (/dev/nst0): Wanted FUL142 have FUL147
21-Okt 12:00 backup-sd-bb JobId 54512: Please mount Volume "FUL142" for:
    Job:          RestoreFiles.2008-10-21_11.58.06
    Storage:      "JBBB-1" (/dev/nst0)
    Pool:         FUL
    Media type:   SDLT-320


next we put the tape into the changer:

*update slots
3306 Issuing autochanger "list" command.
Catalog record for Volume "FUL142" updated to reference slot 1.
Catalog record for Volume "INC008" is up to date.
Catalog record for Volume "INC009" is up to date.


*mount
The defined Storage resources are:
     1: JBGS
     2: JBBB
Select Storage resource (1-4): 2
Enter autochanger drive[0]:
Enter autochanger slot: 1
3001 OK mount. Device="JBBB-1" (/dev/nst0)
You have messages.
*m
21-Okt 12:09 backup-sd-bb JobId 54512: 3301 Issuing autochanger "loaded? drive 
0" command.
21-Okt 12:09 backup-sd-bb JobId 54512: 3302 Autochanger "loaded? drive 0", 
result: nothing loaded.
21-Okt 12:09 backup-sd-bb JobId 54512: 3304 Issuing autochanger "load slot 25, 
drive 0" command.
1-Okt 12:10 backup-sd-bb JobId 54512: 3305 Autochanger "load slot 25, drive 0", 
status is OK.
21-Okt 12:10 backup-sd-bb JobId 54512: 3307 Issuing autochanger "unload slot 
25, drive 0" command.
21-Okt 12:10 backup-sd-bb JobId 54512: Warning: acquire.c:260 Wrong Volume 
mounted on device "JBBB-1" (/dev/nst0): Wanted FUL142 have FUL147
21-Okt 12:10 backup-sd-bb JobId 54512: 3301 Issuing autochanger "loaded? drive 
0" command.
21-Okt 12:10 backup-sd-bb JobId 54512: 3302 Autochanger "loaded? drive 0", 
result: nothing loaded.
21-Okt 12:10 backup-sd-bb JobId 54512: 3304 Issuing autochanger "load slot 25, 
drive 0" command.
21-Okt 12:11 backup-sd-bb JobId 54512: 3305 Autochanger "load slot 25, drive 
0", status is OK.
21-Okt 12:11 backup-sd-bb JobId 54512: 3307 Issuing autochanger "unload slot 
25, drive 0" command.
21-Okt 12:11 backup-sd-bb JobId 54512: Warning: acquire.c:260 Wrong Volume 
mounted on device "JBBB-1" (/dev/nst0): Wanted FUL142 have FUL147
21-Okt 12:11 backup-sd-bb JobId 54512: Please mount Volume "FUL142" for:
    Job:          RestoreFiles.2008-10-21_11.58.06
    Storage:      "JBBB-1" (/dev/nst0)
    Pool:         FUL
    Media type:   SDLT-320



grmpf.



Thomas schrieb:
> last month i had the problem described below,
> i will verify if it happens again with 2.4.3.
> 
> 
> starting a restore without the needed tape in the jukebox:
> (FUL138 is the needed tape)
> 
> 2008-09-18 14:54:41   Start Restore Job RestoreFiles.2008-09-18_14.54.04
> 2008-09-18 14:54:41   Using Device "JBBB-1"
> 2008-09-18 14:54:42   Invalid slot=0 defined in catalog for Volume "FUL138" 
> on "JBBB-1" (/dev/nst0). Manual load may be required.
> 2008-09-18 14:54:42   3301 Issuing autochanger "loaded? drive 0" command.
> 2008-09-18 14:54:42   3302 Autochanger "loaded? drive 0", result: nothing 
> loaded.
> 2008-09-18 14:54:42   Warning: acquire.c:221 Read open device "JBBB-1" 
> (/dev/nst0) Volume "FUL138" failed: ERR=dev.c:432 Unable to open device 
> "JBBB-1" (/dev/nst0): ERR=No medium found
> 2008-09-18 14:54:42   3301 Issuing autochanger "loaded? drive 0" command.
> 2008-09-18 14:54:42   3302 Autochanger "loaded? drive 0", result: nothing 
> loaded.
> 2008-09-18 14:54:42   3304 Issuing autochanger "load slot 22, drive 0" 
> command.
> 2008-09-18 14:55:23   3305 Autochanger "load slot 22, drive 0", status is OK.
> 2008-09-18 14:55:35   3307 Issuing autochanger "unload slot 22, drive 0" 
> command.
> 2008-09-18 14:56:02   Warning: acquire.c:260 Wrong Volume mounted on device 
> "JBBB-1" (/dev/nst0): Wanted FUL138 have FUL140
> 2008-09-18 14:56:02   Please mount Volume "FUL138" for:
> Job: RestoreFiles.2008-09-18_14.54.04
> Storage: "JBBB-1" (/dev/nst0)
> Pool: FUL
> Media type: SDLT-320
> 
> 
> bacula noticed that an invalid slot is defined
> (=> the tape is not in the changer)
> and then it loads the current tape from the slot
> where the correct volume was last seen.
> (the slot in the mediatable points to 22 for volume FUL138
> but the inchanger-flag is set to 0)
> 
> next we put the tape FUL138 into the changer at a random slot (21).
> 
> run update slots:
> 
> Device "JBBB" has 26 slots.
> Connecting to Storage daemon JBBB at backup-sd-bb.ic3s.de:9103 ...
> 3306 Issuing autochanger "list" command.
> ....
> Catalog record for Volume "FUL138" updated to reference slot 21.
> Catalog record for Volume "FUL140" updated to reference slot 22.
> ....
> *
> 
> and then we mount slot 21:
> 
> *mount
> The defined Storage resources are:
>      1: JBGS
>      2: JBBB
>      3: FileStorageBB
>      4: USBStorageBB1
> Select Storage resource (1-4): 2
> Enter autochanger drive[0]:
> Enter autochanger slot: 21
> 3001 OK mount. Device="JBBB-1" (/dev/nst0)
> You have messages.
> *m
> 18-Sep 15:09 backup-sd-bb JobId 52022: 3301 Issuing autochanger "loaded? 
> drive 0" command.
> 18-Sep 15:09 backup-sd-bb JobId 52022: 3302 Autochanger "loaded? drive 0", 
> result: nothing loaded.
> 18-Sep 15:09 backup-sd-bb JobId 52022: 3304 Issuing autochanger "load slot 
> 22, drive 0" command.
> 18-Sep 15:10 backup-sd-bb JobId 52022: 3305 Autochanger "load slot 22, drive 
> 0", status is OK.
> 18-Sep 15:10 backup-sd-bb JobId 52022: 3307 Issuing autochanger "unload slot 
> 22, drive 0" command.
> 18-Sep 15:11 backup-sd-bb JobId 52022: Warning: acquire.c:260 Wrong Volume 
> mounted on device "JBBB-1" (/dev/nst0): Wanted FUL138 have FUL140
> 18-Sep 15:11 backup-sd-bb JobId 52022: 3301 Issuing autochanger "loaded? 
> drive 0" command.
> 18-Sep 15:11 backup-sd-bb JobId 52022: 3302 Autochanger "loaded? drive 0", 
> result: nothing loaded.
> 18-Sep 15:11 backup-sd-bb JobId 52022: 3304 Issuing autochanger "load slot 
> 22, drive 0" command.
> 18-Sep 15:11 backup-sd-bb JobId 52022: 3305 Autochanger "load slot 22, drive 
> 0", status is OK.
> 18-Sep 15:11 backup-sd-bb JobId 52022: 3307 Issuing autochanger "unload slot 
> 22, drive 0" command.
> 18-Sep 15:12 backup-sd-bb JobId 52022: Warning: acquire.c:260 Wrong Volume 
> mounted on device "JBBB-1" (/dev/nst0): Wanted FUL138 have FUL140
> 18-Sep 15:12 backup-sd-bb JobId 52022: Please mount Volume "FUL138" for:
>     Job:          RestoreFiles.2008-09-18_14.54.04
>     Storage:      "JBBB-1" (/dev/nst0)
>     Pool:         FUL
>     Media type:   SDLT-320
> 
> 
> bacula still mounts slot 22 instead of the one we entered on the mount 
> command.
> very strange  :(
> we tried it a few times but the only way to go on with the restore
> was to put the tape FUL138 into slot 22!
> 
> 
> 
> 
> Alan Brown schrieb:
>> 2.4.2-1 (Scott Barninger's RPM, but that shouldn't make any difference)
>>
>> Using an autochanger.
>>
>> Restores which only use a single tape are fine.
>>
>> For multiple tape restores, even thought he tapes are in various slots,
>> Bacula is insisting on loading all the tapes it wants from the first slot
>> it uses.
>>
>> IE: tape A,B,C,D are in slot 1,2,3,4
>>
>> Bacula loads Tape C from slot 3.
>>
>> Bacula then wants Tape A, but tries to load it from slot 3 and complains.
>>
>> If Tape A is manually loaded, Bacula unloads it, loads slot 3 and then
>> complains.
>>
>> If Tape A is put in Slot 3 bacula loads it happily.
>>
>> Repeat for Tapes B and D.
>>
>> Can anyone else verify?
>>
>>
>> AB
>>
>>
>> -------------------------------------------------------------------------
>> This SF.Net email is sponsored by the Moblin Your Move Developer's challenge
>> Build the coolest Linux based applications with Moblin SDK & win great prizes
>> Grand prize is a trip for two to an Open Source event anywhere in the world
>> http://moblin-contest.org/redirect.php?banner_id=100&url=/
>> _______________________________________________
>> Bacula-users mailing list
>> Bacula-users AT lists.sourceforge DOT net
>> https://lists.sourceforge.net/lists/listinfo/bacula-users
> 

-- 
[:O]###[O:]

-------------------------------------------------------------------------
This SF.Net email is sponsored by the Moblin Your Move Developer's challenge
Build the coolest Linux based applications with Moblin SDK & win great prizes
Grand prize is a trip for two to an Open Source event anywhere in the world
http://moblin-contest.org/redirect.php?banner_id=100&url=/
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users

<Prev in Thread] Current Thread [Next in Thread>