Amanda-Users

Re: 'amtape update' coredumps

2003-05-14 09:35:08
Subject: Re: 'amtape update' coredumps
From: Brian Cuttler <brian AT wadsworth DOT org>
To: Jean-Francois Malouin <Jean-Francois.Malouin AT bic.mni.mcgill DOT ca>
Date: Wed, 14 May 2003 09:32:35 -0400 (EDT)
Jean-Francois,

I am sorry that I don't have better news for you. All I have to
offer you at the moment which may not be applicable (Solaris 9,
LTO drives in Sun Storedge 9 [ie HP jukebox]) is that changing
the shell in the changer clue script (in my case 
/usr/local/libexec/chg-zd-mtx) to /bin/ksh from /bin/sh helped
and completely with amcheck though I still have issues with
amtape and amlabel with the slot option.

                                                Brian

---
   Brian R Cuttler                 brian.cuttler AT wadsworth DOT org
   Computer Systems Support        (v) 518 486-1697
   Wadsworth Center                (f) 518 473-6384
   NYS Department of Health        Help Desk 518 473-0773



> 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>