Hi,
Setup: SGI Origin3000, Irix 6.5.14m, Changer: STK L40, 4 Seagate LTOs
Amanda-2.4.4 split in 4 configurations, each owning one tape
and 10 slots in the changer, with chg-zd-mtx glue script.
Since I've upgraded to amanda-2.4.4, running 'amtape <conf> update'
coredumps. Amanda reads the label of one tape, update the changer
label database, then drops the ball when reaching for the next tape.
I've appended the debug output of chg-zd-mtx and amtape just before
the core is dumped and the state of amtape when it terminated.
My programming skills being rusty, can someone shed some light
on this.
TIA,
jf
---
chg-zd-mtx: debug 1 pid 4205656 ruid 666 euid 666: start at Tue May 13
15:42:00
2003
15:42:00 Arg info:
$# = 2
$0 = "/opt/amanda/amanda2/libexec/chg-zd-mtx"
$1 = "-label"
$2 = "stk_40-conf2-000009"
15:42:00 Running: /opt/bin/mtx status
15:42:04 Exit code: 0
Stdout:
Storage Changer /dev/changer:4 Drives, 42 Slots ( 2 Import/Export )
Data Transfer Element 0:Full (Storage Element 1 Loaded):VolumeTag = 000010
Data Transfer Element 1:Full (Storage Element 19 Loaded):VolumeTag = 000049
Data Transfer Element 2:Empty
Data Transfer Element 3:Empty
Storage Element 1:Empty
Storage Element 2:Full :VolumeTag=000016
Storage Element 3:Full :VolumeTag=000017
Storage Element 4:Full :VolumeTag=000018
Storage Element 5:Full :VolumeTag=000019
Storage Element 6:Full :VolumeTag=000021
Storage Element 7:Full :VolumeTag=000040
Storage Element 8:Full :VolumeTag=000011
Storage Element 9:Full :VolumeTag=000012
Storage Element 10:Full :VolumeTag=000015
Storage Element 11:Full :VolumeTag=000041
Storage Element 12:Full :VolumeTag=000042
Storage Element 13:Full :VolumeTag=000043
Storage Element 14:Full :VolumeTag=000044
Storage Element 15:Full :VolumeTag=000045
Storage Element 16:Full :VolumeTag=000046
Storage Element 17:Full :VolumeTag=000047
Storage Element 18:Full :VolumeTag=000048
Storage Element 19:Empty
Storage Element 20:Full :VolumeTag=000050
[snip: empty slots follow]
15:42:04 Config info:
firstslot = "11"
lastslot = "20"
cleanslot = "-1"
cleancycle = "120"
offline_before_unload = "1"
unloadpause = "0"
autoclean = "0"
autocleancount = "99"
havereader = "1"
driveslot = "1"
poll_drive_ready = "30"
max_drive_wait = "120"
15:42:04 STATUS -> currently loaded slot = 19
-> currently loaded barcode = "000049"
15:42:04 LABEL -> Adding label "stk_40-conf2-000009" with barcode "000049" fo
r slot 19 into
/opt/amanda/amanda2/etc/amanda/stk_40-conf2/stk_40-barcodes
-> appended
/opt/amanda/amanda2/etc/amanda/stk_40-conf2/stk_40-barcodes entry:
stk_40-conf2-000009 000049
15:42:04 Exit (0) -> 19 /hw/tape/tps13d2nrnsv
chg-zd-mtx: pid 4230788 finish time Tue May 13 15:42:04 2003
---
The amtape debug:
amtape: debug 1 pid 4227776 ruid 666 euid 666: start at Tue May 13 15:44:15 2003
changer: got exit: 0 str: 20 10 1 1
changer: got exit: 0 str: 20 /hw/tape/tps13d2nrnsv
changer_label: stk_40-conf2-000010 for slot 20
changer: got exit: 0 str: 20 10 1 1
changer_query: changer return was 10 1 1
changer_query: searchable = 1
changer_label: calling changer -label stk_40-conf2-000010
changer: got exit: 0 str: 20 /hw/tape/tps13d2nrnsv
---
Running a debugger on the core:
bullcalf:amanda-conf2# dbx /opt/amanda/amanda2/sbin/amtape
core20030513
dbx version 7.3 MR 55458_Apr30_MR Apr 30 1999 13:44:41
dbx Warning: Unknown processor type 0xe, assuming this is not an R8000
Core from signal SIGBUS: Bus error
(dbx) where
> 0 realfree(0x1001c5f3, 0x145, 0x32, 0x11, 0x10, 0x6, 0x1001bf58, 0xfb523f4)
> ["/xlv46/6.5.14m/work/irix/lib/libc/libc_n32_M4/gen/malloc.c":503, 0xfb0cfbc]
1 cleanfree(0x0, 0x145, 0x32, 0x11, 0x10, 0x6, 0x1001bf58, 0xfb523f4)
["/xlv46/6.5.14m/work/irix/lib/libc/libc_n32_M4/gen/malloc.c":944, 0xfb0d830]
2 __malloc(0x0, 0x145, 0x32, 0x11, 0x10, 0x6, 0x1001bf58, 0xfb523f4)
["/xlv46/6.5.14m/work/irix/lib/libc/libc_n32_M4/gen/malloc.c":230, 0xfb0ca50]
3 _malloc(0x0, 0x145, 0x32, 0x11, 0x10, 0x6, 0x1001bf58, 0xfb523f4)
["/xlv46/6.5.14m/work/irix/lib/libc/libc_n32_M4/gen/malloc.c":186, 0xfb0c8c0]
4 debug_alloc(s = 0x5fff4010 = "changer.c", l = 325, size = 50)
["/stuff/amanda/amanda-2.4.4/common-src/alloc.c":218, 0x401d8d4]
5 internal_vstralloc(str = 0x1001db20 =
"/opt/amanda/amanda2/libexec/chg-zd-mtx", argp = 0x7fff2c10 = "")
["/stuff/amanda/amanda-2.4.4/common-src/alloc.c":323, 0x401dca0]
6 debug_vstralloc(str = 0x1001db20 =
"/opt/amanda/amanda2/libexec/chg-zd-mtx", ... = <void>)
["/stuff/amanda/amanda-2.4.4/common-src/alloc.c":345, 0x401dddc]
7 changer_command(cmd = 0x5fff7a70 = "-slot", arg = 0x5fff7a90 = "next")
["/stuff/amanda/amanda-2.4.4/server-src/changer.c":325, 0x5ffc72f0]
8 run_changer_command(cmd = 0x5fff7a70 = "-slot", arg = 0x5fff7a90 = "next",
slotstr = 0x7fff2e04, rest = 0x7fff2db0)
["/stuff/amanda/amanda-2.4.4/server-src/changer.c":93, 0x5ffc6308]
9 changer_loadslot(inslotstr = 0x5fff7a90 = "next", outslotstr = 0x7fff2e04,
devicename = 0x7fff2e00)
["/stuff/amanda/amanda-2.4.4/server-src/changer.c":148, 0x5ffc67cc]
10 changer_scan(user_init = 0x100038b0, user_slot = 0x100039e0)
["/stuff/amanda/amanda-2.4.4/server-src/changer.c":221, 0x5ffc6bd0]
11 update_labeldb(argc = 1, argv = 0x7fff2f2c)
["/stuff/amanda/amanda-2.4.4/server-src/amtape.c":463, 0x10003e48]
12 main(argc = 1, argv = 0x7fff2f2c)
["/stuff/amanda/amanda-2.4.4/server-src/amtape.c":177, 0x10002b08]
13 __start()
["/xlv55/kudzu-apr12/work/irix/lib/libc/libc_n32_M3/csu/crt1text.s":177,
0x10002178]
--
Excess on occasion is exhilarating. It prevents moderation from
acquiring the deadening effect of a habit.
|