Hello Users!
I hope someone with autochanger expierience can help me with my concerns....
First of all my environment:
- bacula 1.38.9 (from source)
- mtx 1.2.18rel (from rpm)
- SuSE 9.0 Kernel 2.4.21-99
- Exabyte 221L TapeLibrary (21 slots in 3 Magazines, Barcodereader, 1 IBM Ultrium LTO-2 drive)
I don't think problem is in configure-options nor in bacula-xxx.confs since backups succeed. So none of them here.
I just get those strange statements in log over and over again, and I'm not sure wether someday this may lead to a crash.
Concerning the "Bad autochanger loaded drive 0 command" I guess it might be a timing problem, but can't decide wether it's the mtx-changer or bacula itself. Do you know anything?
(I'll add my mtx-changer)
Concerning the "Unloading / Reloading tape for follow-up job": Just why??? It's just sometimes happening, not each time!
Today both happened in once, so here is the part of my log:
JobId: 155
Job: Job1_____________.2006-06-27_22.12.15
Backup Level: Full
Client: "Client1" Windows Server 2003,MVS,NT 5.2.3790
FileSet: "FileSet1" 2006-06-13 20:16:29
Pool: "Daily"
Storage: "TapeLibrary"
Scheduled time: 27-Jun-2006 22:12:10
Start time: 27-Jun-2006 22:12:20
End time: 28-Jun-2006 00:55:04
Elapsed time: 2 hours 42 mins 44 secs
Priority: 10
FD Files Written: 3,903
SD Files Written: 3,903
FD Bytes Written: 122,954,473,051 (122.9 GB)
SD Bytes Written: 122,955,147,172 (122.9 GB)
Rate: 12592.6 KB/s
Software Compression: None
Volume name(s): TUE001L2
Volume Session Id: 1
Volume Session Time: 1151424310
Last Volume Bytes: 123,046,455,465 (123.0 GB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
28-Jun 00:55 heem-dir: Begin pruning Jobs.
28-Jun 00:55 heem-dir: No Jobs found to prune.
28-Jun 00:55 heem-dir: Begin pruning Files.
28-Jun 00:55 heem-dir: No Files found to prune.
28-Jun 00:55 heem-dir: End auto prune.
28-Jun 00:55 heem-dir: Start Backup JobId 156, Job2_________.2006-06-27_23.00.00
28-Jun 00:55 heem-sd: 3301 Issuing autochanger "loaded drive 0" command.
28-Jun 00:55 heem-sd: 3991 Bad autochanger "loaded drive 0" command: ERR=Operation not permitted.
28-Jun 00:55 heem-sd: 3301 Issuing autochanger "loaded drive 0" command.
28-Jun 00:55 heem-sd: 3302 Autochanger "loaded drive 0", result is Slot 3.
28-Jun 00:55 heem-sd: 3307 Issuing autochanger "unload slot 3, drive 0" command.
28-Jun 00:55 heem-sd: 3304 Issuing autochanger "load slot 3, drive 0" command.
28-Jun 00:56 heem-sd: 3305 Autochanger "load slot 3, drive 0", status is OK.
28-Jun 00:56 heem-sd: 3301 Issuing autochanger "loaded drive 0" command.
28-Jun 00:56 heem-sd: 3302 Autochanger "loaded drive 0", result is Slot 3.
28-Jun 00:56 heem-sd: Volume "TUE001L2" previously written, moving to end of data.
28-Jun 00:56 heem-sd: Ready to append to end of Volume "TUE001L2" at file=124.
28-Jun 01:52 heem-dir: Bacula 1.38.9 (02May06): 28-Jun-2006 01:52:29
JobId: 156
Job: Job2_________.2006-06-27_23.00.00
Backup Level: Full
Client: "Client2" i686-pc-linux-gnu,suse,9.0
FileSet: "FileSet2" 2006-06-15 00:45:31
Pool: "Daily"
Storage: "TapeLibrary"
Scheduled time: 27-Jun-2006 23:00:00
Start time: 28-Jun-2006 00:55:08
End time: 28-Jun-2006 01:52:29
Elapsed time: 57 mins 21 secs
Priority: 10
FD Files Written: 110,844
SD Files Written: 110,844
FD Bytes Written: 28,747,087,799 (28.74 GB)
SD Bytes Written: 28,768,112,206 (28.76 GB)
Rate: 8354.3 KB/s
Software Compression: None
Volume name(s): TUE001L2
Volume Session Id: 2
Volume Session Time: 1151424310
Last Volume Bytes: 151,839,027,155 (151.8 GB)
Non-fatal FD errors: 0
SD Errors: 0
FD termination status: OK
SD termination status: OK
Termination: Backup OK
In the 3rd job, bacula doesen't repeat the "loaded drive 0" command even though it gets an "ERR...".
But this time at least it doesn't unload/load the tape but goes on immediately. (see below)
I don't understand this behaviour.
28-Jun 01:52 heem-dir: Start Backup JobId 157, Job3________.2006-06-28_00.10.01
28-Jun 01:52 heem-sd: 3301 Issuing autochanger "loaded drive 0" command.
28-Jun 01:52 heem-sd: 3302 Autochanger "loaded drive 0", result is Slot 3.
28-Jun 01:52 heem-sd: 3301 Issuing autochanger "loaded drive 0" command.
28-Jun 01:52 heem-sd: 3991 Bad autochanger "loaded drive 0" command: ERR=Operation not permitted.
28-Jun 01:52 heem-sd: Volume "TUE001L2" previously written, moving to end of data.
28-Jun 01:53 heem-sd: Ready to append to end of Volume "TUE001L2" at file=153.
28-Jun 02:32 heem-dir: Bacula 1.38.9 (02May06): 28-Jun-2006 02:32:50
.....
Thanks in advance!
Best Regards,
i. A. Christoff Buch
=====================================
[EMAIL PROTECTED]
+49.941.78004.607
[EMAIL PROTECTED]
OneVision Software AG
Dr.-Leo-Ritter-Str. 9
D - 93049 Regensburg
mtx-changer.OneVision_productive.doc
Description: Binary data
Using Tomcat but need to do more? Need to support web services, security? Get stuff done quickly with pre-integrated technology to make your job easier Download IBM WebSphere Application Server v.1.0.1 based on Apache Geronimo http://sel.as-us.falkag.net/sel?cmd=lnk&kid=120709&bid=263057&dat=121642
_______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
