Amanda-Users

Re: 'amtape update' coredumps

2003-05-23 16:05:50
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 10:25:41 -0400 (EDT)
Sorry that wasn't it... the symptoms are so very similar, well, identical.

Hey, that is why I qualified it with my architecture info...

> * Brian Cuttler (brian AT wadsworth DOT org) [20030514 09:33] thus spake:
> > 
> > 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.
> 
> On irix6 /bin/sh *is* a Korn shell
> as 'man sh' will tell you.
> 
> I don't think the glue script is at fault.
> Some memory allocation is most probably it.
> 
> thanks anyway,
> jf
> 
> > 
> >                                             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.
> 
> -- 
> Excess on occasion is exhilarating.  It prevents moderation from
> acquiring the deadening effect of a habit.


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