Hi,
An amanda run last night ended up in the holddisk due to the fact
that chg-zd-mtx didn't hunt for the requested tape even it's loaded and
it is in 'reuse' mode. Note that previous to the run I had put a tape in
`no-reuse' mode but that tape wasn't in the expected set of tapes for
this run. Amanda-2.6.0p2 btw.
I have attached the debug files but essentially the events went like
this: the tape drive got unloaded (the drive was empty so nothing
really happened there), then current slot was loaded, then an attemp to add
its label to the amanda barcode file but the barcode was already
in there, then proceeded to load the next slot containing the tape
that amanda was expecting but it just kept going, trying the sync the
label to the barcodes file, and that went on for the remaining slots
and amanda finally gave up after scanning the whole slot list.
Any ideas? I just did a 'amtape $conf label _some_label' without
a glitch, amanda hunting and loading the right tape but I see the
same pattern right now after trying the flush the hold disk to tape.
thanks!
jf
--
<° >< Jean-François Malouin McConnell Brain Imaging Centre
Systems/Network Administrator Montréal Neurological Institute
3801 Rue University, Suite WB219, Montréal, Québec, H3A 2B4, Canada
1229587861.899720: chg-zd-mtx: pid 31699 ruid 105 euid 105: start at Thu Dec 18
03:11:01 2008
03:11:02 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:11:02 Arg info:
$# = 1
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-info"
03:11:02 Running: /usr/sbin/mtxRunning: status
03:11:03 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Empty
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Full :VolumeTag=V00043L3
03:11:03 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:11:03 STATUS -> currently loaded slot = -1
-> currently loaded barcode = ""
03:11:03 INFO -> first slot: 13
-> current slot: 23
-> loaded slot: -1
-> last slot: 24
-> slot list: 13 -> slot list: 14 -> slot list: 15 -> slot list: 16 ->
slot list: 17 -> slot list: 18 -> slot list: 19 -> slot list: 20 -> slot list:
21 -> slot list: 22 -> slot list: 23 -> slot list: 24
-> can go backwards: 1
-> havereader: 1
03:11:03 Exit (0) -> 23 12 1 1
1229587863.221753: chg-zd-mtx: pid 31886 finish time Thu Dec 18 03:11:03 2008
1229587863.247516: chg-zd-mtx: pid 31898 ruid 105 euid 105: start at Thu Dec 18
03:11:03 2008
03:11:03 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:11:03 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-slot"
$2 = "current"
03:11:03 Running: /usr/sbin/mtxRunning: status
03:11:04 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Empty
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Full :VolumeTag=V00043L3
03:11:04 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:11:04 LOADSLOT -> load drive 1 (/dev/nst1) from slot current
03:11:04 STATUS -> currently loaded slot = -1
-> currently loaded barcode = ""
03:11:04 EJECT -> ejecting tape from /dev/nst1
03:11:04 STATUS -> currently loaded slot = -1
-> currently loaded barcode = ""
03:11:04 Return (1) -> -1 Drive was not loaded
03:11:04 LOADSLOT -> loading tape from slot 23 to drive 1 (/dev/nst1)
03:11:04 Running: /usr/sbin/mtxRunning: loadRunning: 23Running: 1
03:12:00 Exit code: 0
03:12:00 -> status 0, result ""
03:12:10 Exit (0) -> 23 tape:/dev/nst1
1229587930.385295: chg-zd-mtx: pid 32138 finish time Thu Dec 18 03:12:10 2008
1229587934.404386: chg-zd-mtx: pid 32150 ruid 105 euid 105: start at Thu Dec 18
03:12:14 2008
03:12:14 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:12:14 Arg info:
$# = 1
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-info"
03:12:14 Running: /usr/sbin/mtxRunning: status
03:12:15 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 23 Loaded):VolumeTag = V00044L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Empty
Storage Element 24:Full :VolumeTag=V00043L3
03:12:15 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:12:15 STATUS -> currently loaded slot = 23
-> currently loaded barcode = "V00044L3"
03:12:15 INFO -> first slot: 13
-> current slot: 23
-> loaded slot: 23
-> last slot: 24
-> slot list: 13 -> slot list: 14 -> slot list: 15 -> slot list: 16 ->
slot list: 17 -> slot list: 18 -> slot list: 19 -> slot list: 20 -> slot list:
21 -> slot list: 22 -> slot list: 23 -> slot list: 24
-> can go backwards: 1
-> havereader: 1
03:12:15 Exit (0) -> 23 12 1 1
1229587935.612348: chg-zd-mtx: pid 32328 finish time Thu Dec 18 03:12:15 2008
1229587935.637906: chg-zd-mtx: pid 32340 ruid 105 euid 105: start at Thu Dec 18
03:12:15 2008
03:12:15 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:12:15 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-label"
$2 = "av24-2_right2_V00044L3"
03:12:15 Running: /usr/sbin/mtxRunning: status
03:12:16 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 23 Loaded):VolumeTag = V00044L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Empty
Storage Element 24:Full :VolumeTag=V00043L3
03:12:16 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:12:16 STATUS -> currently loaded slot = 23
-> currently loaded barcode = "V00044L3"
03:12:16 LABEL -> Adding label "av24-2_right2_V00044L3" with barcode "V00044L3"
for slot 23 into /opt/amanda/etc/amanda/right2/av24-2-barcodes
-> already synced
03:12:16 Exit (0) -> 23 tape:/dev/nst1
1229587936.915501: chg-zd-mtx: pid 32565 finish time Thu Dec 18 03:12:16 2008
1229587936.941214: chg-zd-mtx: pid 32577 ruid 105 euid 105: start at Thu Dec 18
03:12:16 2008
03:12:17 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:12:17 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-slot"
$2 = "next"
03:12:17 Running: /usr/sbin/mtxRunning: status
03:12:18 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 23 Loaded):VolumeTag = V00044L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Empty
Storage Element 24:Full :VolumeTag=V00043L3
03:12:18 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:12:18 LOADSLOT -> load drive 1 (/dev/nst1) from slot next
03:12:18 STATUS -> currently loaded slot = 23
-> currently loaded barcode = "V00044L3"
03:12:18 EJECT -> ejecting tape from /dev/nst1
03:12:18 STATUS -> currently loaded slot = 23
-> currently loaded barcode = "V00044L3"
03:12:18 EJECT -> moving tape from drive 1 to storage slot 23
03:12:18 Running: try_eject_deviceRunning: /dev/nst1
03:12:36 Exit code: 0
03:12:36 Running: /usr/sbin/mtxRunning: unloadRunning: 23Running: 1
03:13:20 Exit code: 0
Stderr:
Unloading Data Transfer Element into Storage Element 23...done
03:13:20 -> status 0, result "Unloading" -> status Data, result "Transfer" ->
status Element, result "into" -> status Storage, result "Element" -> status
23...done, result ""
03:13:20 Return (0) -> 23 tape:/dev/nst1
03:13:20 LOADSLOT -> loading tape from slot 24 to drive 1 (/dev/nst1)
03:13:20 Running: /usr/sbin/mtxRunning: loadRunning: 24Running: 1
03:14:13 Exit code: 0
03:14:13 -> status 0, result ""
03:14:23 Exit (0) -> 24 tape:/dev/nst1
1229588063.266059: chg-zd-mtx: pid 392 finish time Thu Dec 18 03:14:23 2008
1229588067.277977: chg-zd-mtx: pid 407 ruid 105 euid 105: start at Thu Dec 18
03:14:27 2008
03:14:27 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:14:27 Arg info:
$# = 1
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-info"
03:14:27 Running: /usr/sbin/mtxRunning: status
03:14:28 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 24 Loaded):VolumeTag = V00043L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Empty
03:14:28 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:14:28 STATUS -> currently loaded slot = 24
-> currently loaded barcode = "V00043L3"
03:14:28 INFO -> first slot: 13
-> current slot: 24
-> loaded slot: 24
-> last slot: 24
-> slot list: 13 -> slot list: 14 -> slot list: 15 -> slot list: 16 ->
slot list: 17 -> slot list: 18 -> slot list: 19 -> slot list: 20 -> slot list:
21 -> slot list: 22 -> slot list: 23 -> slot list: 24
-> can go backwards: 1
-> havereader: 1
03:14:28 Exit (0) -> 24 12 1 1
1229588068.486416: chg-zd-mtx: pid 588 finish time Thu Dec 18 03:14:28 2008
1229588068.511859: chg-zd-mtx: pid 600 ruid 105 euid 105: start at Thu Dec 18
03:14:28 2008
03:14:28 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:14:28 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-label"
$2 = "av24-2_right2_V00043L3"
03:14:28 Running: /usr/sbin/mtxRunning: status
03:14:29 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 24 Loaded):VolumeTag = V00043L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Empty
03:14:29 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:14:29 STATUS -> currently loaded slot = 24
-> currently loaded barcode = "V00043L3"
03:14:29 LABEL -> Adding label "av24-2_right2_V00043L3" with barcode "V00043L3"
for slot 24 into /opt/amanda/etc/amanda/right2/av24-2-barcodes
-> already synced
03:14:29 Exit (0) -> 24 tape:/dev/nst1
1229588069.780408: chg-zd-mtx: pid 825 finish time Thu Dec 18 03:14:29 2008
1229588069.805819: chg-zd-mtx: pid 837 ruid 105 euid 105: start at Thu Dec 18
03:14:29 2008
03:14:29 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:14:29 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-slot"
$2 = "next"
03:14:29 Running: /usr/sbin/mtxRunning: status
03:14:30 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 24 Loaded):VolumeTag = V00043L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Full :VolumeTag=V00042L3
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Empty
03:14:30 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:14:30 LOADSLOT -> load drive 1 (/dev/nst1) from slot next
03:14:31 STATUS -> currently loaded slot = 24
-> currently loaded barcode = "V00043L3"
03:14:31 EJECT -> ejecting tape from /dev/nst1
03:14:31 STATUS -> currently loaded slot = 24
-> currently loaded barcode = "V00043L3"
03:14:31 EJECT -> moving tape from drive 1 to storage slot 24
03:14:31 Running: try_eject_deviceRunning: /dev/nst1
03:14:49 Exit code: 0
03:14:49 Running: /usr/sbin/mtxRunning: unloadRunning: 24Running: 1
03:15:35 Exit code: 0
Stderr:
Unloading Data Transfer Element into Storage Element 24...done
03:15:35 -> status 0, result "Unloading" -> status Data, result "Transfer" ->
status Element, result "into" -> status Storage, result "Element" -> status
24...done, result ""
03:15:35 Return (0) -> 24 tape:/dev/nst1
03:15:35 LOADSLOT -> loading tape from slot 13 to drive 1 (/dev/nst1)
03:15:35 Running: /usr/sbin/mtxRunning: loadRunning: 13Running: 1
03:16:36 Exit code: 0
03:16:36 -> status 0, result ""
03:16:46 Exit (0) -> 13 tape:/dev/nst1
1229588206.553978: chg-zd-mtx: pid 1127 finish time Thu Dec 18 03:16:46 2008
1229588210.779341: chg-zd-mtx: pid 1141 ruid 105 euid 105: start at Thu Dec 18
03:16:50 2008
03:16:50 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:16:50 Arg info:
$# = 1
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-info"
03:16:50 Running: /usr/sbin/mtxRunning: status
03:16:51 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 13 Loaded):VolumeTag = V00042L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Empty
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Full :VolumeTag=V00043L3
03:16:51 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:16:51 STATUS -> currently loaded slot = 13
-> currently loaded barcode = "V00042L3"
03:16:51 INFO -> first slot: 13
-> current slot: 13
-> loaded slot: 13
-> last slot: 24
-> slot list: 13 -> slot list: 14 -> slot list: 15 -> slot list: 16 ->
slot list: 17 -> slot list: 18 -> slot list: 19 -> slot list: 20 -> slot list:
21 -> slot list: 22 -> slot list: 23 -> slot list: 24
-> can go backwards: 1
-> havereader: 1
03:16:51 Exit (0) -> 13 12 1 1
1229588211.985795: chg-zd-mtx: pid 1319 finish time Thu Dec 18 03:16:51 2008
1229588212.011240: chg-zd-mtx: pid 1331 ruid 105 euid 105: start at Thu Dec 18
03:16:52 2008
03:16:52 Using config file /opt/amanda/etc/amanda/right2/av24-2.conf
03:16:52 Arg info:
$# = 2
$0 = "/opt/amanda-2.6.0p2/libexec/amanda/chg-zd-mtx"
$1 = "-label"
$2 = "av24-2_right2_V00042L3"
03:16:52 Running: /usr/sbin/mtxRunning: status
03:16:53 Exit code: 0
Stdout:
Storage Changer /dev/changer:2 Drives, 24 Slots ( 0 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Full (Storage Element 13 Loaded):VolumeTag = V00042L3
Storage Element 1:Full :VolumeTag=U00043L3
Storage Element 2:Full :VolumeTag=U00042L3
Storage Element 3:Full :VolumeTag=U00015L3
Storage Element 4:Full :VolumeTag=U00045L3
Storage Element 5:Full :VolumeTag=U00046L3
Storage Element 6:Full :VolumeTag=U00016L3
Storage Element 7:Full :VolumeTag=U00017L3
Storage Element 8:Full :VolumeTag=U00049L3
Storage Element 9:Full :VolumeTag=U00044L3
Storage Element 10:Full :VolumeTag=U00048L3
Storage Element 11:Full :VolumeTag=U00047L3
Storage Element 12:Full :VolumeTag=U00029L3
Storage Element 13:Empty
Storage Element 14:Full :VolumeTag=V00041L3
Storage Element 15:Full :VolumeTag=V00040L3
Storage Element 16:Full :VolumeTag=V00027L3
Storage Element 17:Full :VolumeTag=V00004L3
Storage Element 18:Full :VolumeTag=V00049L3
Storage Element 19:Full :VolumeTag=V00000L3
Storage Element 20:Full :VolumeTag=V00029L3
Storage Element 21:Full :VolumeTag=V00028L3
Storage Element 22:Full :VolumeTag=V00005L3
Storage Element 23:Full :VolumeTag=V00044L3
Storage Element 24:Full :VolumeTag=V00043L3
03:16:53 Config info:
firstslot = "13"
lastslot = "24"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
initial_poll_delay = "10"
max_drive_wait = "120"
03:16:53 STATUS -> currently loaded slot = 13
-> currently loaded barcode = "V00042L3"
03:16:53 LABEL -> Adding label "av24-2_right2_V00042L3" with barcode "V00042L3"
for slot 13 into /opt/amanda/etc/amanda/right2/av24-2-barcodes
-> already synced
03:16:53 Exit (0) -> 13 tape:/dev/nst1
1229588213.274742: chg-zd-mtx: pid 1560 finish time Thu Dec 18 03:16:53 2008
