Hi Arno, I activated the wait_for_drive function in /etc/bacula/mtx-changer, and the warnings went away!
Yippeee!! You rule!! 8-) Cheers, Mike Hi, 18.09.2007 20:30,, Mike Seda wrote:: > > Hi All, > > I just conducted a successful restore job via bconsole, but received > > some weird warning messages. Suspiciously, these warnings started to > > appear after a library (robotics and drive) firmware upgrade. > > > > The warning that I receive is: > > Warning: acquire.c:200 Read open device "Drive-1" (/dev/nst0) Volume > > "MSR115L3" failed: ERR=dev.c:424 Unable to open device "Drive-1" > > (/dev/nst0): ERR=Input/output error Is it possible that, in your mtx-changer script, you use a hard-coded wait time after loading a tape? If that's the case, I suspect that the new firmware made autochanger operations take longer, and thus your timeout is overrun. If that's the case, you should activate the wait_for_drive function and use that. If you already use that, I'd suggest to add a hard-coded wait time after autochanger operations, in case the new firmware makes mtx return even before the actual operation is finished. Arno > > The complete restore job output is provided below: > > 18-Sep 14:02 uwharrie-dir: Start Restore Job > > RestoreFiles.2007-09-18_14.02.42 > > 18-Sep 14:02 uwharrie-sd: 3307 Issuing autochanger "unload slot 1, drive > > 0" command. > > 18-Sep 14:03 uwharrie-sd: 3304 Issuing autochanger "load slot 11, drive > > 0" command. > > 18-Sep 14:03 uwharrie-sd: 3305 Autochanger "load slot 11, drive 0", > > status is OK. > > 18-Sep 14:03 uwharrie-sd: 3301 Issuing autochanger "loaded? drive 0" > > command. > > 18-Sep 14:03 uwharrie-sd: 3302 Autochanger "loaded? drive 0", result is > > Slot 11. > > 18-Sep 14:03 uwharrie-sd: RestoreFiles.2007-09-18_14.02.42 Warning: > > acquire.c:200 Read open device "Drive-1" (/dev/nst0) Volume "MSR115L3" > > failed: ERR=dev.c:424 Unable to open device "Drive-1" (/dev/nst0): > > ERR=Input/output error > > > > 18-Sep 14:03 uwharrie-sd: 3301 Issuing autochanger "loaded? drive 0" > > command. > > 18-Sep 14:03 uwharrie-sd: 3302 Autochanger "loaded? drive 0", result is > > Slot 11. > > 18-Sep 14:03 uwharrie-sd: RestoreFiles.2007-09-18_14.02.42 Warning: > > acquire.c:200 Read open device "Drive-1" (/dev/nst0) Volume "MSR115L3" > > failed: ERR=dev.c:424 Unable to open device "Drive-1" (/dev/nst0): > > ERR=Input/output error > > > > 18-Sep 14:03 uwharrie-sd: Please mount Volume "MSR115L3" on Storage > > Device "Drive-1" (/dev/nst0) for Job RestoreFiles.2007-09-18_14.02.42 > > 18-Sep 14:06 uwharrie-sd: 3301 Issuing autochanger "loaded? drive 0" > > command. > > 18-Sep 14:06 uwharrie-sd: 3302 Autochanger "loaded? drive 0", result is > > Slot 11. > > 18-Sep 14:06 uwharrie-sd: Ready to read from volume "MSR115L3" on device > > "Drive-1" (/dev/nst0). > > 18-Sep 14:06 uwharrie-sd: Forward spacing Volume "MSR115L3" to > > file:block 692:0. > > 18-Sep 14:08 uwharrie-sd: End of Volume at file 692 on device "Drive-1" > > (/dev/nst0), Volume "MSR115L3" > > 18-Sep 14:08 uwharrie-sd: End of all volumes. > > 18-Sep 14:08 uwharrie-dir: Bacula 2.0.1 (12Jan07): 18-Sep-2007 14:08:34 > > JobId: 2306 > > Job: RestoreFiles.2007-09-18_14.02.42 > > Client: uwharrie-fd > > Start time: 18-Sep-2007 14:02:44 > > End time: 18-Sep-2007 14:08:34 > > Files Expected: 1 > > Files Restored: 1 > > Bytes Restored: 32,813 > > Rate: 0.1 KB/s > > FD Errors: 0 > > FD termination status: OK > > SD termination status: OK > > Termination: Restore OK > > > > I also received a similar warning message at bconsole by simply mounting > > a different tape: > > *mount > > The defined Storage resources are: > > 1: Tape > > 2: File > > Select Storage resource (1-2): 1 > > Connecting to Storage daemon Tape at uwharrie:9103 ... > > Enter autochanger slot: 1 > > 3301 Issuing autochanger "loaded? drive 0" command. > > 3302 Autochanger "loaded? drive 0", result: nothing loaded. > > 3304 Issuing autochanger "load slot 1, drive 0" command. > > 3305 Autochanger "load slot 1, drive 0", status is OK. > > 3301 Issuing autochanger "loaded? drive 0" command. > > 3302 Autochanger "loaded? drive 0", result is Slot 1. > > 3901 open device failed: ERR=dev.c:424 Unable to open device "Drive-1" > > (/dev/nst0): ERR=Input/output error > > > > My hardware/software configuration is: > > - Dell PE 2650 (1 x 73 GB RAID 1 (OS), 1 x 1.3 TB RAID 0 (data spooling > > partition) , 4 GB RAM, 2 GB swap) > > - Quantum PX502 (LTO-3, FC, 1-Drive) > > - RHEL 4 AS > > - Bacula 2.0.1 (Pools=Weekly, Monthly, Scratch, Migrate, Archive) > > > > Any thoughts? > > > > Regards, > > Mike > > > > > > ------------------------------------------------------------------------- > > This SF.net email is sponsored by: Microsoft > > Defy all challenges. Microsoft(R) Visual Studio 2005. > > http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ > > _______________________________________________ > > Bacula-users mailing list > > Bacula-users@lists.sourceforge.net > > https://lists.sourceforge.net/lists/listinfo/bacula-users -- Arno Lehmann IT-Service Lehmann www.its-lehmann.de ------------------------------------------------------------------------- This SF.net email is sponsored by: Microsoft Defy all challenges. Microsoft(R) Visual Studio 2005. http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/ _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
