Re: amanda 3.3 - Amrecover 'Always' segfaults - how to debug this ?? Is it related to amanda-client.conf ??

Mon, 26 Sep 2011 06:53:39 -0700 

On 09/26/2011 09:33 AM, Morgan Cox wrote:

Hi.

Thanks for the response.

Here is the gdb log:-

---------------------------------------------------------------------------------------------------------
Starting program: /usr/sbin/amrecover
warning: no loadable sections found in added symbol-file system-supplied DSO at 0x2aaaaaaab000 
[Thread debugging using libthread_db enabled]
Detaching after fork from child process 19694.
Detaching after fork from child process 19715.

Program received signal SIGSEGV, Segmentation fault.
0x000000000040a73f in extract_files ()
A debugging session is active.

        Inferior 1 [process 19691] will be killed.

---------------------------------------------------------------------------------------------------------

And the amrecover debug log:-

---------------------------------------------------------------------------------------------------------
Mon Sep 26 14:04:13 2011: amrecover: pid 18471 ruid 0 euid 0 version 3.3.0: start at Mon Sep 26 14:04:13 2011 
Mon Sep 26 14:04:13 2011: amrecover: config_overrides: conf BackupSet1
Mon Sep 26 14:04:13 2011: amrecover: pid 18471 ruid 0 euid 0 version 3.3.0: rename at Mon Sep 26 14:04:13 2011 Mon Sep 26 14:04:13 2011: amrecover: security_getdriver(name=local) returns 0x39d766b540 Mon Sep 26 14:04:13 2011: amrecover: security_handleinit(handle=0xaa24130, driver=0x39d766b540 (LOCAL)) Mon Sep 26 14:04:13 2011: amrecover: security_streaminit(stream=0xaa266a0, driver=0x39d766b540 (LOCAL)) Mon Sep 26 14:04:13 2011: amrecover: tcpm_send_token: data is still flowing Mon Sep 26 14:04:13 2011: amrecover: security_streaminit(stream=0xaa2e700, driver=0x39d766b540 (LOCAL)) Mon Sep 26 14:04:13 2011: amrecover: security_close(handle=0xaa24130, driver=0x39d766b540 (LOCAL)) 
Mon Sep 26 14:04:13 2011: amrecover: security_stream_close(0xaa266a0)
Mon Sep 26 14:04:13 2011: amrecover: sending: FEATURES ffffffff9efefbffffffffff0f 


Mon Sep 26 14:04:13 2011: amrecover: sending: DATE 2011-09-26


Mon Sep 26 14:04:13 2011: amrecover: sending: SCNF BackupSet1
Mon Sep 26 14:04:13 2011: amrecover: sending: HOST backup.cwcs.co.uk <http://backup.cwcs.co.uk>
Mon Sep 26 14:04:13 2011: amrecover: sending: HOST backup.cwcs.co.uk <http://backup.cwcs.co.uk>
Mon Sep 26 14:04:13 2011: amrecover: sending: HOST backup.cwcs.co.uk <http://backup.cwcs.co.uk> 


Mon Sep 26 14:04:24 2011: amrecover: user command: 'sethost localhost'
Mon Sep 26 14:04:24 2011: amrecover: sending: HOST localhost
Mon Sep 26 14:04:24 2011: amrecover: tcpm_send_token: data is still flowing 
Mon Sep 26 14:04:28 2011: amrecover: user command: 'setdisk /data'
Mon Sep 26 14:04:28 2011: amrecover: sending: DISK /data


Mon Sep 26 14:04:28 2011: amrecover: sending: OISD /


Mon Sep 26 14:04:28 2011: amrecover: sending: OLSD /
Mon Sep 26 14:04:28 2011: amrecover: add_dir_list_item: Adding "2011-09-22-15-00-01" "0" "BackupSet1-10:1;BackupSet1-1:1;BackupSet1-2:1;BackupSet1-3:1;BackupSet1-4:1" "1" "/." Mon Sep 26 14:04:28 2011: amrecover: add_dir_list_item: Adding "2011-09-22-15-00-01" "0" "BackupSet1-10:1;BackupSet1-1:1;BackupSet1-2:1;BackupSet1-3:1;BackupSet1-4:1" "1" "/backup/" Mon Sep 26 14:04:28 2011: amrecover: add_dir_list_item: Adding "2011-09-22-15-00-01" "0" "BackupSet1-10:1;BackupSet1-1:1;BackupSet1-2:1;BackupSet1-3:1;BackupSet1-4:1" "1" "/gdb.txt" Mon Sep 26 14:04:28 2011: amrecover: add_dir_list_item: Adding "2011-09-22-15-00-01" "0" "BackupSet1-10:1;BackupSet1-1:1;BackupSet1-2:1;BackupSet1-3:1;BackupSet1-4:1" "1" "/r1soft/" 
Mon Sep 26 14:04:28 2011: amrecover: sending: DLE


Mon Sep 26 14:04:29 2011: amrecover: user command: 'ls'
Mon Sep 26 14:04:33 2011: amrecover: user command: 'add gdb.txt'
Mon Sep 26 14:04:33 2011: amrecover: add_glob (gdb.txt) -> ^gdb\.txt$
Mon Sep 26 14:04:33 2011: amrecover: add_file: Looking for "gdb\.txt[/]*$"
Mon Sep 26 14:04:33 2011: amrecover: add_file: Converted path="gdb\.txt[/]*$" to path_on_disk="/gdb\.txt[/]*$" Mon Sep 26 14:04:33 2011: amrecover: add_file: Pondering ditem->path=/r1soft/ Mon Sep 26 14:04:33 2011: amrecover: add_file: Pondering ditem->path=/gdb.txt 
Mon Sep 26 14:04:33 2011: amrecover: add_file: (Successful) Added /gdb.txt
Mon Sep 26 14:04:33 2011: amrecover: add_file: Pondering ditem->path=/backup/ 
Mon Sep 26 14:04:33 2011: amrecover: add_file: Pondering ditem->path=/.
Mon Sep 26 14:04:36 2011: amrecover: user command: 'extract'
Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=(nil), label='BackupSet1-10', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-10', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-1', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-1', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-2', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-2', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-3', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-3', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-4', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa46f20, label='BackupSet1-4', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=(nil), label='BackupSet1-10', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-10', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-1', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-1', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-2', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-2', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-3', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-3', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-4', file=-1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: append_to_tapelist(tapelist=0xaa48620, label='BackupSet1-4', file=1, partnum=-1, isafile=0) Mon Sep 26 14:04:36 2011: amrecover: Requesting tape BackupSet1-10 from user Mon Sep 26 14:04:37 2011: amrecover: User prompt: 'Continue [?/Y/n/s/d]? '; response: 'Y' Mon Sep 26 14:04:37 2011: amrecover: security_getdriver(name=local) returns 0x39d766b540 Mon Sep 26 14:04:37 2011: amrecover: security_handleinit(handle=0xaa469f0, driver=0x39d766b540 (LOCAL)) Mon Sep 26 14:04:37 2011: amrecover: security_streaminit(stream=0xaa4f4a0, driver=0x39d766b540 (LOCAL)) Mon Sep 26 14:04:37 2011: amrecover: tcpm_send_token: data is still flowing Mon Sep 26 14:04:37 2011: amrecover: security_streaminit(stream=0xaa57500, driver=0x39d766b540 (LOCAL)) 
Mon Sep 26 14:04:37 2011: amrecover: amidxtaped_streams[0].fd = 0xaa57500
Mon Sep 26 14:04:37 2011: amrecover: security_streaminit(stream=0xaa5f560, driver=0x39d766b540 (LOCAL)) 
Mon Sep 26 14:04:37 2011: amrecover: amidxtaped_streams[1].fd = 0xaa5f560
Mon Sep 26 14:04:37 2011: amrecover: security_close(handle=0xaa469f0, driver=0x39d766b540 (LOCAL)) 
Mon Sep 26 14:04:37 2011: amrecover: security_stream_close(0xaa4f4a0)
Mon Sep 26 14:04:37 2011: amrecover: send_to_tape_server: FEATURES=ffffffff9efefbffffffffff0f
Mon Sep 26 14:04:37 2011: amrecover: security_stream_seterr(0xaa57500, EOF) 

---------------------------------------------------------------------------------------------------------


Any ideas what is occurring ?

(i'm 1/2 assuming its autochanger related)

If you need anything else please let me know ...

The last line show an EOF, it must be amandad or amidxtaped that fail.
Post the amandad and amidxtaped debug files.


 Also - can I attach txt files on this mailing list ?

It is the preferred way to post debug file.
Jean-Louis




Regards
On 26 September 2011 13:13, Jean-Louis Martineau <[email protected] <mailto:[email protected]>> wrote: 

    On 09/26/2011 07:17 AM, Morgan Cox wrote:

        Hi.

        Its a bit disconcerting that my test seemed to work but now
        crashes when I try to recover

        Is it likely to be a config option ? Or is the tape / software
        possibly broken?

        Any help would be very welcomed.

        - is it config, compression related ?

        Everytime I go to 'extract' a file it segfaults - i.e :-

        ------------------------------------------------------------
        amrecover> sethost localhost
        200 Dump host set to localhost.
        amrecover> setdisk /data
        200 Disk set to /data.
        amrecover> ls
        2011-09-22-15-00-01 r1soft/
        2011-09-22-15-00-01 gdb.txt
        2011-09-22-15-00-01 backup/
        2011-09-22-15-00-01 .
        amrecover> add gdb.txt
        Added file /gdb.txt
        amrecover> extact
        Invalid command: extact
        amrecover> extract

        Extracting files using tape drive changer on host localhost.
        The following tapes are needed: BackupSet1-10 BackupSet1-1
        BackupSet1-2 BackupSet1-3 BackupSet1-4

        Extracting files using tape drive changer on host localhost.
        Load tape BackupSet1-10 now
        Continue [?/Y/n/s/d]? y
        Segmentation fault


    Post the amrecover debug file.

    Also, run amrecover under gdb and print the backtrace after the
    segfault.

    Jean-Louis

Reply via email to