Amanda-Users

'amtape update' coredumps

2003-05-14 08:48:34
Subject: 'amtape update' coredumps
From: Jean-Francois Malouin <Jean-Francois.Malouin AT bic.mni.mcgill DOT ca>
To: AMANDA users <amanda-users AT amanda DOT org>
Date: Wed, 14 May 2003 08:45:35 -0400
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.

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