Amanda-Users

Re: 'amtape update' coredumps

2003-05-14 10:26:34
Subject: Re: 'amtape update' coredumps
From: Jean-Louis Martineau <martinea AT IRO.UMontreal DOT CA>
To: Jean-Francois Malouin <Jean-Francois.Malouin AT bic.mni.mcgill DOT ca>
Date: Wed, 14 May 2003 10:24:21 -0400
Hi Jean-François,

It's broken in 2.4.4, it was fixed the day after the 2.4.4 release.
You need a recent snapshot from http://www.iro.umontreal.ca/~martinea/amanda

Jean-Louis

On Wed, May 14, 2003 at 08:45:35AM -0400, Jean-Francois Malouin wrote:
> 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.

-- 
Jean-Louis Martineau             email: martineau AT IRO.UMontreal DOT CA 
Departement IRO, Universite de Montreal
C.P. 6128, Succ. CENTRE-VILLE    Tel: (514) 343-6111 ext. 3529
Montreal, Canada, H3C 3J7        Fax: (514) 343-5834

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