Page 1 of 2 12 LastLast
Results 1 to 10 of 11

Thread: Backup recovery speed

  1. #1
    Join Date
    Mar 2016
    Posts
    13

    Default Backup recovery speed

    Hello

    I have backup on disk, I have set my tape size to 10TB to avoid problems

    When the first backup is done it's generates a big file of 2TB of the full backup 00001.srv-test-01._rep_grp.0
    The incremental is small like 300M

    The problem is that to recovery any file looks like amanda read the entire full dump, this takes hours because it is a 2TB file

    I want to speed up the recovery process

    If I create a tape size smaller that forces amanda to create various small parts of the full backup will amanda jump over directly to the part that has the file or it will read all the files the same way?

    Is there any way to speed up the process?

    Thanks

  2. #2
    Join Date
    Nov 2005
    Location
    Canada
    Posts
    1,044

    Default

    The only way to do it in amanda-3.3 and earlier is to split the dle in multiple smaller dles.

    but amanda-3.4 with the amgtar application do what you want, it only read the required data from tape.

  3. #3
    Join Date
    Mar 2016
    Posts
    13

    Default

    My amanda version is 3.3.7 but it has amgtar...

    I will test to see if it's works... thanks for the help.

  4. #4
    Join Date
    Nov 2005
    Location
    Canada
    Posts
    1,044

    Default

    This feature is available only in amanda-3.4

  5. #5
    Join Date
    Mar 2016
    Posts
    13

    Default

    Hello

    I have upgraded amanda to 3.4 and have done a backup of 2TB using this settings, but amrecover still reads the entire 2TB only to restore a single file.

    Could you spot any problem in this configuration?

    Thanks

    Note: I use "compress none" because the destination is a dedupe engine.

    define application-tool app_amgtar {

    plugin "amgtar"
    property "VERBOSE" "YES"
    property "ATIME-PRESERVE" "YES"

    }


    define dumptype amgtar_app_dtyp {

    auth "ssh"
    index true
    ssh_keys "/etc/amanda/keys/ssh-key"
    maxpromoteday 1
    program "APPLICATION"
    application "app_amgtar"
    compress none
    }


    Disklist example:

    srv-backup-06.xxx.xx.xx /dir amgtar_app_dtyp

  6. #6
    Join Date
    Nov 2005
    Location
    Canada
    Posts
    1,044

    Default

    Do the backup was done with a 3.4 client and server?
    Post the amrecover.*.debug and the amidxtaped.*.debug files

  7. #7
    Join Date
    Mar 2016
    Posts
    13

    Default

    The client version is 3.3.7 (amanda-backup-client_3.3.7-1Ubuntu1204_amd64.deb)

    This is the log file
    https://expirebox.com/download/7e73deee23d6994c85253dc2604bfefd.html

    Thanks

  8. #8
    Join Date
    Nov 2005
    Location
    Canada
    Posts
    1,044

    Default

    The backup must have be done with a 3.4 client and server.
    The restore must be done with a 3.4 client and server.

    I already said that feature is available only with 3.4
    Stop trying with 3.3.7, it will never works

  9. #9
    Join Date
    Mar 2016
    Posts
    13

    Default

    Hello
    I have update the cliente to 3.4
    If I do a small backup it works, but with big backups (2TB+) gives this error:


    0 kb
    /bin/tar: This does not look like a tar archive
    /bin/tar: ./seti-diti-diapl/VMware-converter-en-5.1.0-1087880.exe: Not found in archive
    /bin/tar: Exiting with failure status due to previous errors
    ERROR /bin/tar exited with status 2: see /var/log/amanda/client/backup-teste/amgtar.20170413103109.debug
    Extractor child exited with status 1


    Inside of /var/log/amanda/client/backup-teste/amgtar.20170413103109.debug in the last line tell us to look in the same file "amgtar.20170413103109.debug" so it is redundant
    This is the beginning of the debug file, the rest of the file is only a listing of files inside the backup

    Thu Apr 13 10:31:09.193543942 2017: pid 24129: thd-0x1e3cc00: amgtar: pid 24129 ruid 0 euid 0 version 3.4: start at Thu Apr 13 10:31:09 2017
    Thu Apr 13 10:31:09.193616106 2017: pid 24129: thd-0x1e3cc00: amgtar: version 3.4
    Thu Apr 13 10:31:09.193786583 2017: pid 24129: thd-0x1e3cc00: amgtar: reading config file /etc/amanda/amanda-client.conf
    Thu Apr 13 10:31:09.193954954 2017: pid 24129: thd-0x1e3cc00: amgtar: state_stream: 13
    Thu Apr 13 10:31:09.194838934 2017: pid 24129: thd-0x1e3cc00: amgtar: pid 24129 ruid 0 euid 0 version 3.4: rename at Thu Apr 13 10:31:09 2017
    Thu Apr 13 10:31:09.194888718 2017: pid 24129: thd-0x1e3cc00: amgtar: GNUTAR-PATH /bin/tar
    Thu Apr 13 10:31:09.194899007 2017: pid 24129: thd-0x1e3cc00: amgtar: GNUTAR-LISTDIR /var/lib/amanda/gnutar-lists
    Thu Apr 13 10:31:09.194907456 2017: pid 24129: thd-0x1e3cc00: amgtar: ONE-FILE-SYSTEM yes
    Thu Apr 13 10:31:09.194915409 2017: pid 24129: thd-0x1e3cc00: amgtar: SPARSE yes
    Thu Apr 13 10:31:09.194922851 2017: pid 24129: thd-0x1e3cc00: amgtar: NO-UNQUOTE no
    Thu Apr 13 10:31:09.194930203 2017: pid 24129: thd-0x1e3cc00: amgtar: ATIME-PRESERVE no
    Thu Apr 13 10:31:09.194937488 2017: pid 24129: thd-0x1e3cc00: amgtar: ACLS no
    Thu Apr 13 10:31:09.194944756 2017: pid 24129: thd-0x1e3cc00: amgtar: SELINUX no
    Thu Apr 13 10:31:09.194951915 2017: pid 24129: thd-0x1e3cc00: amgtar: XATTRS no
    Thu Apr 13 10:31:09.194958993 2017: pid 24129: thd-0x1e3cc00: amgtar: CHECK-DEVICE yes
    Thu Apr 13 10:31:09.194966481 2017: pid 24129: thd-0x1e3cc00: amgtar: SIZE ^ *Total bytes written: [0-9][0-9]*
    Thu Apr 13 10:31:09.194974491 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE : Directory is new$
    Thu Apr 13 10:31:09.194982203 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE : Directory has been renamed
    Thu Apr 13 10:31:09.194989965 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE file changed as we read it$
    Thu Apr 13 10:31:09.194997597 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^could not open conf file
    Thu Apr 13 10:31:09.195008830 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^Elapsed time:
    Thu Apr 13 10:31:09.195020063 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^Throughput
    Thu Apr 13 10:31:09.195030809 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : directory is on a different filesystem; not dumped
    Thu Apr 13 10:31:09.195043057 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : File .* shrunk by [0-9][0-9]* bytes, padding with zeros
    Thu Apr 13 10:31:09.195056142 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : Cannot add file .*: No such file or directory$
    Thu Apr 13 10:31:09.195064778 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : Error exit delayed from previous errors
    Thu Apr 13 10:31:09.195072497 2017: pid 24129: thd-0x1e3cc00: amgtar: STRANGE : socket ignored$
    Thu Apr 13 10:31:09.195079956 2017: pid 24129: thd-0x1e3cc00: amgtar: ERROR amgtar: error
    Thu Apr 13 10:31:09.196116615 2017: pid 24129: thd-0x1e3cc00: amgtar: recover_dump_state_file: 0 /


    This is the part relevant inside amrecover log:

    Thu Apr 13 10:31:19.757483129 2017: pid 23856: thd-0x1c82c00: amrecover: send_to_tape_server: DAR-DONE
    Thu Apr 13 10:31:19.757527116 2017: pid 23856: thd-0x1c82c00: amrecover: tcpm_send_token: data is still flowing
    Thu Apr 13 10:31:19.761182185 2017: pid 23856: thd-0x1c82c00: amrecover: get amidxtaped line: MESSAGE 0 kb
    Thu Apr 13 10:31:19.800029468 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_seterr(0x1cf9b60, EOF)
    Thu Apr 13 10:31:19.800111790 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_seterr(0x1d01c00, EOF)
    Thu Apr 13 10:31:19.800137903 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_close(0x1d01c00)
    Thu Apr 13 10:31:19.801667673 2017: pid 23856: thd-0x1c82c00: amrecover: native_crc: 9cde9cb7:2293844213760
    Thu Apr 13 10:31:19.801696764 2017: pid 23856: thd-0x1c82c00: amrecover: client_crc: 9cde9cb7:2293844213760
    Thu Apr 13 10:31:19.801707489 2017: pid 23856: thd-0x1c82c00: amrecover: server_crc: 00000000:0
    Thu Apr 13 10:31:19.801719456 2017: pid 23856: thd-0x1c82c00: amrecover: crc_in : 00000000:0
    Thu Apr 13 10:31:19.801728150 2017: pid 23856: thd-0x1c82c00: amrecover: crc_native: 00000000:0
    Thu Apr 13 10:31:19.801788406 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_close(0x1cf9b60)


    This is amidxtaped:

    Wed Apr 12 09:44:43.397000619 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 ruid 63998 euid 63998 version 3.4: start at Wed Apr 12 09:44:43 2017
    Wed Apr 12 09:44:43.440215324 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << FEATURES=ffffffff9efefbfffffffffffffff3fffbf70f
    Wed Apr 12 09:44:43.440829709 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << CONFIG=backup-teste
    Wed Apr 12 09:44:43.480126180 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << LABEL=backup-teste:backup-teste01:1
    Wed Apr 12 09:44:43.480215381 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << FSF=1
    Wed Apr 12 09:44:43.480288845 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER
    Wed Apr 12 09:44:43.480368074 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DEVICE=changer
    Wed Apr 12 09:44:43.480444394 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HOST=^srv-backup-06.uffs.edu.br$
    Wed Apr 12 09:44:43.480581052 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DISK=^/espelho/FITA/Servers/dmz/srv-repositorio-01\.uffs\.edu\.br/repositorio/grupos$
    Wed Apr 12 09:44:43.480646439 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATESTAMP=20170411112501
    Wed Apr 12 09:44:43.480683148 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << END
    Wed Apr 12 09:44:43.480783877 2017: pid 2801: thd-0x1196c00: amidxtaped: their_features have fe_amrecover_stream_state
    Wed Apr 12 09:44:43.480975574 2017: pid 2801: thd-0x1196c00: amidxtaped: reading config file /etc/amanda/backup-teste/amanda.conf
    Wed Apr 12 09:44:43.483025611 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 ruid 63998 euid 63998 version 3.4: rename at Wed Apr 12 09:44:43 2017
    Wed Apr 12 09:44:43.505356600 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Dir /mnt/datadomain/backup-teste/vtapes
    Wed Apr 12 09:44:43.505393472 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Using statefile '/mnt/datadomain/backup-teste/vtapes/state'
    Wed Apr 12 09:44:43.552281610 2017: pid 2801: thd-0x1196c00: amidxtaped: beginning trace log: /var/lib/amanda/backup-teste/state/log/log.20170412094443.0
    Wed Apr 12 09:44:43.554280444 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Dir /mnt/datadomain/backup-teste/vtapes
    Wed Apr 12 09:44:43.554313736 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Using statefile '/mnt/datadomain/backup-teste/vtapes/state'
    Wed Apr 12 09:44:43.554998361 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: loading volume 'backup-teste01'
    Wed Apr 12 09:44:43.555334940 2017: pid 2801: thd-0x1196c00: amidxtaped: find_volume labeled 'backup-teste01'
    Wed Apr 12 09:44:43.570562006 2017: pid 2801: thd-0x1196c00: amidxtaped: parse_inventory: load slot 1 with label 'backup-teste01'
    Wed Apr 12 09:44:43.570867410 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Recovery/Scan.pm:307:info:1200000 slot 1
    Wed Apr 12 09:44:43.573324186 2017: pid 2801: thd-0x1196c00: amidxtaped: dir_name: /mnt/datadomain/backup-teste/vtapes/slot1/
    Wed Apr 12 09:44:43.668761242 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Recovery/Scan.pm:459:info:1200001 backup-teste01
    Wed Apr 12 09:44:43.669216457 2017: pid 2801: thd-0x1196c00: amidxtaped: ignoring spurious Amanda::Recovery::Scan abort call
    Wed Apr 12 09:44:43.822891665 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: successfully located first part for recovery
    Wed Apr 12 09:44:43.831992390 2017: pid 2801: thd-0x1196c00: amidxtaped: warning: could not find ParserDetails.ini in /usr/local/share/perl/5.18.2/XML/SAX

    Wed Apr 12 09:44:43.929381054 2017: pid 2801: thd-0x1196c00: amidxtaped: Building type FILE header of 128-32768 bytes with name='srv-backup-06.uffs.edu.br' disk='/espelho/FITA/Servers/dmz/srv-repositorio-01.uffs.edu.br/repositorio/grupos' dumplevel=0 and blocksize=32768
    Wed Apr 12 09:44:43.929786060 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> HEADER-SEND-SIZE 2064
    Wed Apr 12 09:44:43.930426043 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER-READY
    Wed Apr 12 09:44:44.508017376 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER-DONE
    Wed Apr 12 09:44:44.508258219 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> STATE-SEND
    Wed Apr 12 09:44:44.508619084 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << STATE-READY
    Wed Apr 12 09:44:46.424145169 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << STATE-DONE
    Wed Apr 12 09:44:46.424322226 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> USE-DAR YES
    Wed Apr 12 09:44:46.424816818 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << USE-DAR YES
    Wed Apr 12 09:44:46.464121220 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << AVAIL-DATAPATH AMANDA
    Wed Apr 12 09:44:46.464811192 2017: pid 2801: thd-0x1196c00: amidxtaped: Starting <Xfer@0x24371e0 (<XferSourceRecovery@0x1fb7000> -> <XferDestFd@0x2464020>)>
    Wed Apr 12 09:44:46.464913074 2017: pid 2801: thd-0x1196c00: amidxtaped: Final linkage: <XferSourceRecovery@0x1fb7000> -(PULL_BUFFER)-> <XferElementGlue@0x2465010> -(WRITEFD)-> <XferDestFd@0x2464020>
    Wed Apr 12 09:44:46.464943843 2017: pid 2801: thd-0x1196c00: amidxtaped: setup_impl: 3, 2
    Wed Apr 12 09:44:46.465108809 2017: pid 2801: thd-0x1196c00: amidxtaped: xfer_queue_message: MSG: <XMsg@0x2440950 type=XMSG_READY elt=<XferSourceRecovery@0x1fb7000> version=0>
    Wed Apr 12 09:44:46.465174382 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> USE-DATAPATH AMANDA
    Wed Apr 12 09:44:46.465254817 2017: pid 2801: thd-0x2466000: amidxtaped: pull_and_write
    Wed Apr 12 09:44:46.466485502 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATAPATH-OK
    Wed Apr 12 09:44:46.466622507 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> DATA-SEND
    Wed Apr 12 09:44:46.467153933 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATA-READY
    Wed Apr 12 09:44:46.467343990 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: starting recovery
    Wed Apr 12 09:44:46.469932172 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: reading file 1 on 'backup-teste01'
    Wed Apr 12 09:44:57.644904115 2017: pid 2801: thd-0x1196c00: amidxtaped: ctl line: DAR-DONE
    Wed Apr 12 09:44:57.645293835 2017: pid 2801: thd-0x2466000: amidxtaped: sending XMSG_CRC message 0x2464020
    Wed Apr 12 09:44:57.645326111 2017: pid 2801: thd-0x2466000: amidxtaped: pull_and_write CRC: 00000000 size 0
    Wed Apr 12 09:44:57.645356714 2017: pid 2801: thd-0x2466000: amidxtaped: xfer_queue_message: MSG: <XMsg@0x7fbbd4001500 type=XMSG_CRC elt=<XferDestFd@0x2464020> version=0>
    Wed Apr 12 09:44:57.645392606 2017: pid 2801: thd-0x2466000: amidxtaped: xfer_queue_message: MSG: <XMsg@0x7fbbd4001570 type=XMSG_DONE elt=<XferElementGlue@0x2465010> version=0>
    Wed Apr 12 09:44:57.646265462 2017: pid 2801: thd-0x1196c00: amidxtaped: dest_crc: 00000000:0
    Wed Apr 12 09:44:57.647160866 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Restore.pm:1892:info:4900012 0 kb
    Wed Apr 12 09:44:57.647711751 2017: pid 2801: thd-0x1196c00: amidxtaped: user_message feedback: 0 kb
    Wed Apr 12 09:44:57.647842022 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> MESSAGE 0 kb
    Wed Apr 12 09:44:57.653029663 2017: pid 2801: thd-0x1196c00: amidxtaped: exiting with 0
    Wed Apr 12 09:44:57.653055388 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_utime : 0
    Wed Apr 12 09:44:57.653060953 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_stime : 0
    Wed Apr 12 09:44:57.653065229 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_maxrss : 38524
    Wed Apr 12 09:44:57.653069790 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_ixrss : 0
    Wed Apr 12 09:44:57.653073802 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_idrss : 0
    Wed Apr 12 09:44:57.653077779 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_isrss : 0
    Wed Apr 12 09:44:57.653081814 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_minflt : 10929
    Wed Apr 12 09:44:57.653085820 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_majflt : 4
    Wed Apr 12 09:44:57.653089780 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nswap : 0
    Wed Apr 12 09:44:57.653093784 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_inblock : 2464
    Wed Apr 12 09:44:57.653097854 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_oublock : 88
    Wed Apr 12 09:44:57.653101891 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_msgsnd : 0
    Wed Apr 12 09:44:57.653111613 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_msgrcv : 0
    Wed Apr 12 09:44:57.653115543 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nsignals: 0
    Wed Apr 12 09:44:57.653119096 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nvcsw : 8447
    Wed Apr 12 09:44:57.653122751 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nivcsw : 187
    Wed Apr 12 09:44:57.653227793 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 finish time Wed Apr 12 09:44:57 2017





    What could be the problem?

  10. #10
    Join Date
    Mar 2016
    Posts
    13

    Default

    Hello
    I have update the cliente to 3.4
    If I do a small backup it works, but with big backups (2TB+) gives this error:


    0 kb
    /bin/tar: This does not look like a tar archive
    /bin/tar: ./seti-diti-diapl/VMware-converter-en-5.1.0-1087880.exe: Not found in archive
    /bin/tar: Exiting with failure status due to previous errors
    ERROR /bin/tar exited with status 2: see /var/log/amanda/client/backup-teste/amgtar.20170413103109.debug
    Extractor child exited with status 1


    Inside of /var/log/amanda/client/backup-teste/amgtar.20170413103109.debug in the last line tell us to look in the same file "amgtar.20170413103109.debug" so it is redundant
    This is the beginning of the debug file, the rest of the file is only a listing of files inside the backup

    Thu Apr 13 10:31:09.193543942 2017: pid 24129: thd-0x1e3cc00: amgtar: pid 24129 ruid 0 euid 0 version 3.4: start at Thu Apr 13 10:31:09 2017
    Thu Apr 13 10:31:09.193616106 2017: pid 24129: thd-0x1e3cc00: amgtar: version 3.4
    Thu Apr 13 10:31:09.193786583 2017: pid 24129: thd-0x1e3cc00: amgtar: reading config file /etc/amanda/amanda-client.conf
    Thu Apr 13 10:31:09.193954954 2017: pid 24129: thd-0x1e3cc00: amgtar: state_stream: 13
    Thu Apr 13 10:31:09.194838934 2017: pid 24129: thd-0x1e3cc00: amgtar: pid 24129 ruid 0 euid 0 version 3.4: rename at Thu Apr 13 10:31:09 2017
    Thu Apr 13 10:31:09.194888718 2017: pid 24129: thd-0x1e3cc00: amgtar: GNUTAR-PATH /bin/tar
    Thu Apr 13 10:31:09.194899007 2017: pid 24129: thd-0x1e3cc00: amgtar: GNUTAR-LISTDIR /var/lib/amanda/gnutar-lists
    Thu Apr 13 10:31:09.194907456 2017: pid 24129: thd-0x1e3cc00: amgtar: ONE-FILE-SYSTEM yes
    Thu Apr 13 10:31:09.194915409 2017: pid 24129: thd-0x1e3cc00: amgtar: SPARSE yes
    Thu Apr 13 10:31:09.194922851 2017: pid 24129: thd-0x1e3cc00: amgtar: NO-UNQUOTE no
    Thu Apr 13 10:31:09.194930203 2017: pid 24129: thd-0x1e3cc00: amgtar: ATIME-PRESERVE no
    Thu Apr 13 10:31:09.194937488 2017: pid 24129: thd-0x1e3cc00: amgtar: ACLS no
    Thu Apr 13 10:31:09.194944756 2017: pid 24129: thd-0x1e3cc00: amgtar: SELINUX no
    Thu Apr 13 10:31:09.194951915 2017: pid 24129: thd-0x1e3cc00: amgtar: XATTRS no
    Thu Apr 13 10:31:09.194958993 2017: pid 24129: thd-0x1e3cc00: amgtar: CHECK-DEVICE yes
    Thu Apr 13 10:31:09.194966481 2017: pid 24129: thd-0x1e3cc00: amgtar: SIZE ^ *Total bytes written: [0-9][0-9]*
    Thu Apr 13 10:31:09.194974491 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE : Directory is new$
    Thu Apr 13 10:31:09.194982203 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE : Directory has been renamed
    Thu Apr 13 10:31:09.194989965 2017: pid 24129: thd-0x1e3cc00: amgtar: IGNORE file changed as we read it$
    Thu Apr 13 10:31:09.194997597 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^could not open conf file
    Thu Apr 13 10:31:09.195008830 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^Elapsed time:
    Thu Apr 13 10:31:09.195020063 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL ^Throughput
    Thu Apr 13 10:31:09.195030809 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : directory is on a different filesystem; not dumped
    Thu Apr 13 10:31:09.195043057 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : File .* shrunk by [0-9][0-9]* bytes, padding with zeros
    Thu Apr 13 10:31:09.195056142 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : Cannot add file .*: No such file or directory$
    Thu Apr 13 10:31:09.195064778 2017: pid 24129: thd-0x1e3cc00: amgtar: NORMAL : Error exit delayed from previous errors
    Thu Apr 13 10:31:09.195072497 2017: pid 24129: thd-0x1e3cc00: amgtar: STRANGE : socket ignored$
    Thu Apr 13 10:31:09.195079956 2017: pid 24129: thd-0x1e3cc00: amgtar: ERROR amgtar: error
    Thu Apr 13 10:31:09.196116615 2017: pid 24129: thd-0x1e3cc00: amgtar: recover_dump_state_file: 0 /


    This is the part relevant inside amrecover log:

    Thu Apr 13 10:31:19.757483129 2017: pid 23856: thd-0x1c82c00: amrecover: send_to_tape_server: DAR-DONE
    Thu Apr 13 10:31:19.757527116 2017: pid 23856: thd-0x1c82c00: amrecover: tcpm_send_token: data is still flowing
    Thu Apr 13 10:31:19.761182185 2017: pid 23856: thd-0x1c82c00: amrecover: get amidxtaped line: MESSAGE 0 kb
    Thu Apr 13 10:31:19.800029468 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_seterr(0x1cf9b60, EOF)
    Thu Apr 13 10:31:19.800111790 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_seterr(0x1d01c00, EOF)
    Thu Apr 13 10:31:19.800137903 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_close(0x1d01c00)
    Thu Apr 13 10:31:19.801667673 2017: pid 23856: thd-0x1c82c00: amrecover: native_crc: 9cde9cb7:2293844213760
    Thu Apr 13 10:31:19.801696764 2017: pid 23856: thd-0x1c82c00: amrecover: client_crc: 9cde9cb7:2293844213760
    Thu Apr 13 10:31:19.801707489 2017: pid 23856: thd-0x1c82c00: amrecover: server_crc: 00000000:0
    Thu Apr 13 10:31:19.801719456 2017: pid 23856: thd-0x1c82c00: amrecover: crc_in : 00000000:0
    Thu Apr 13 10:31:19.801728150 2017: pid 23856: thd-0x1c82c00: amrecover: crc_native: 00000000:0
    Thu Apr 13 10:31:19.801788406 2017: pid 23856: thd-0x1c82c00: amrecover: security_stream_close(0x1cf9b60)


    This is amidxtaped:

    Wed Apr 12 09:44:43.397000619 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 ruid 63998 euid 63998 version 3.4: start at Wed Apr 12 09:44:43 2017
    Wed Apr 12 09:44:43.440215324 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << FEATURES=ffffffff9efefbfffffffffffffff3fffbf70f
    Wed Apr 12 09:44:43.440829709 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << CONFIG=backup-teste
    Wed Apr 12 09:44:43.480126180 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << LABEL=backup-teste:backup-teste01:1
    Wed Apr 12 09:44:43.480215381 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << FSF=1
    Wed Apr 12 09:44:43.480288845 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER
    Wed Apr 12 09:44:43.480368074 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DEVICE=changer
    Wed Apr 12 09:44:43.480444394 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HOST=^srv-backup-06.uffs.edu.br$
    Wed Apr 12 09:44:43.480581052 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DISK=^/espelho/FITA/Servers/dmz/srv-repositorio-01\.uffs\.edu\.br/repositorio/grupos$
    Wed Apr 12 09:44:43.480646439 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATESTAMP=20170411112501
    Wed Apr 12 09:44:43.480683148 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << END
    Wed Apr 12 09:44:43.480783877 2017: pid 2801: thd-0x1196c00: amidxtaped: their_features have fe_amrecover_stream_state
    Wed Apr 12 09:44:43.480975574 2017: pid 2801: thd-0x1196c00: amidxtaped: reading config file /etc/amanda/backup-teste/amanda.conf
    Wed Apr 12 09:44:43.483025611 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 ruid 63998 euid 63998 version 3.4: rename at Wed Apr 12 09:44:43 2017
    Wed Apr 12 09:44:43.505356600 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Dir /mnt/datadomain/backup-teste/vtapes
    Wed Apr 12 09:44:43.505393472 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Using statefile '/mnt/datadomain/backup-teste/vtapes/state'
    Wed Apr 12 09:44:43.552281610 2017: pid 2801: thd-0x1196c00: amidxtaped: beginning trace log: /var/lib/amanda/backup-teste/state/log/log.20170412094443.0
    Wed Apr 12 09:44:43.554280444 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Dir /mnt/datadomain/backup-teste/vtapes
    Wed Apr 12 09:44:43.554313736 2017: pid 2801: thd-0x1196c00: amidxtaped: chg-disk: Using statefile '/mnt/datadomain/backup-teste/vtapes/state'
    Wed Apr 12 09:44:43.554998361 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: loading volume 'backup-teste01'
    Wed Apr 12 09:44:43.555334940 2017: pid 2801: thd-0x1196c00: amidxtaped: find_volume labeled 'backup-teste01'
    Wed Apr 12 09:44:43.570562006 2017: pid 2801: thd-0x1196c00: amidxtaped: parse_inventory: load slot 1 with label 'backup-teste01'
    Wed Apr 12 09:44:43.570867410 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Recovery/Scan.pm:307:info:1200000 slot 1
    Wed Apr 12 09:44:43.573324186 2017: pid 2801: thd-0x1196c00: amidxtaped: dir_name: /mnt/datadomain/backup-teste/vtapes/slot1/
    Wed Apr 12 09:44:43.668761242 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Recovery/Scan.pm:459:info:1200001 backup-teste01
    Wed Apr 12 09:44:43.669216457 2017: pid 2801: thd-0x1196c00: amidxtaped: ignoring spurious Amanda::Recovery::Scan abort call
    Wed Apr 12 09:44:43.822891665 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: successfully located first part for recovery
    Wed Apr 12 09:44:43.831992390 2017: pid 2801: thd-0x1196c00: amidxtaped: warning: could not find ParserDetails.ini in /usr/local/share/perl/5.18.2/XML/SAX

    Wed Apr 12 09:44:43.929381054 2017: pid 2801: thd-0x1196c00: amidxtaped: Building type FILE header of 128-32768 bytes with name='srv-backup-06.uffs.edu.br' disk='/espelho/FITA/Servers/dmz/srv-repositorio-01.uffs.edu.br/repositorio/grupos' dumplevel=0 and blocksize=32768
    Wed Apr 12 09:44:43.929786060 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> HEADER-SEND-SIZE 2064
    Wed Apr 12 09:44:43.930426043 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER-READY
    Wed Apr 12 09:44:44.508017376 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << HEADER-DONE
    Wed Apr 12 09:44:44.508258219 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> STATE-SEND
    Wed Apr 12 09:44:44.508619084 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << STATE-READY
    Wed Apr 12 09:44:46.424145169 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << STATE-DONE
    Wed Apr 12 09:44:46.424322226 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> USE-DAR YES
    Wed Apr 12 09:44:46.424816818 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << USE-DAR YES
    Wed Apr 12 09:44:46.464121220 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << AVAIL-DATAPATH AMANDA
    Wed Apr 12 09:44:46.464811192 2017: pid 2801: thd-0x1196c00: amidxtaped: Starting <Xfer@0x24371e0 (<XferSourceRecovery@0x1fb7000> -> <XferDestFd@0x2464020>)>
    Wed Apr 12 09:44:46.464913074 2017: pid 2801: thd-0x1196c00: amidxtaped: Final linkage: <XferSourceRecovery@0x1fb7000> -(PULL_BUFFER)-> <XferElementGlue@0x2465010> -(WRITEFD)-> <XferDestFd@0x2464020>
    Wed Apr 12 09:44:46.464943843 2017: pid 2801: thd-0x1196c00: amidxtaped: setup_impl: 3, 2
    Wed Apr 12 09:44:46.465108809 2017: pid 2801: thd-0x1196c00: amidxtaped: xfer_queue_message: MSG: <XMsg@0x2440950 type=XMSG_READY elt=<XferSourceRecovery@0x1fb7000> version=0>
    Wed Apr 12 09:44:46.465174382 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> USE-DATAPATH AMANDA
    Wed Apr 12 09:44:46.465254817 2017: pid 2801: thd-0x2466000: amidxtaped: pull_and_write
    Wed Apr 12 09:44:46.466485502 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATAPATH-OK
    Wed Apr 12 09:44:46.466622507 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> DATA-SEND
    Wed Apr 12 09:44:46.467153933 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL << DATA-READY
    Wed Apr 12 09:44:46.467343990 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: starting recovery
    Wed Apr 12 09:44:46.469932172 2017: pid 2801: thd-0x1196c00: amidxtaped: Amanda::Recovery::Clerk: reading file 1 on 'backup-teste01'
    Wed Apr 12 09:44:57.644904115 2017: pid 2801: thd-0x1196c00: amidxtaped: ctl line: DAR-DONE
    Wed Apr 12 09:44:57.645293835 2017: pid 2801: thd-0x2466000: amidxtaped: sending XMSG_CRC message 0x2464020
    Wed Apr 12 09:44:57.645326111 2017: pid 2801: thd-0x2466000: amidxtaped: pull_and_write CRC: 00000000 size 0
    Wed Apr 12 09:44:57.645356714 2017: pid 2801: thd-0x2466000: amidxtaped: xfer_queue_message: MSG: <XMsg@0x7fbbd4001500 type=XMSG_CRC elt=<XferDestFd@0x2464020> version=0>
    Wed Apr 12 09:44:57.645392606 2017: pid 2801: thd-0x2466000: amidxtaped: xfer_queue_message: MSG: <XMsg@0x7fbbd4001570 type=XMSG_DONE elt=<XferElementGlue@0x2465010> version=0>
    Wed Apr 12 09:44:57.646265462 2017: pid 2801: thd-0x1196c00: amidxtaped: dest_crc: 00000000:0
    Wed Apr 12 09:44:57.647160866 2017: pid 2801: thd-0x1196c00: amidxtaped: /usr/local/share/perl/5.18.2/Amanda/Restore.pm:1892:info:4900012 0 kb
    Wed Apr 12 09:44:57.647711751 2017: pid 2801: thd-0x1196c00: amidxtaped: user_message feedback: 0 kb
    Wed Apr 12 09:44:57.647842022 2017: pid 2801: thd-0x1196c00: amidxtaped: CTL >> MESSAGE 0 kb
    Wed Apr 12 09:44:57.653029663 2017: pid 2801: thd-0x1196c00: amidxtaped: exiting with 0
    Wed Apr 12 09:44:57.653055388 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_utime : 0
    Wed Apr 12 09:44:57.653060953 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_stime : 0
    Wed Apr 12 09:44:57.653065229 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_maxrss : 38524
    Wed Apr 12 09:44:57.653069790 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_ixrss : 0
    Wed Apr 12 09:44:57.653073802 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_idrss : 0
    Wed Apr 12 09:44:57.653077779 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_isrss : 0
    Wed Apr 12 09:44:57.653081814 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_minflt : 10929
    Wed Apr 12 09:44:57.653085820 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_majflt : 4
    Wed Apr 12 09:44:57.653089780 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nswap : 0
    Wed Apr 12 09:44:57.653093784 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_inblock : 2464
    Wed Apr 12 09:44:57.653097854 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_oublock : 88
    Wed Apr 12 09:44:57.653101891 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_msgsnd : 0
    Wed Apr 12 09:44:57.653111613 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_msgrcv : 0
    Wed Apr 12 09:44:57.653115543 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nsignals: 0
    Wed Apr 12 09:44:57.653119096 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nvcsw : 8447
    Wed Apr 12 09:44:57.653122751 2017: pid 2801: thd-0x1196c00: amidxtaped: ru_nivcsw : 187
    Wed Apr 12 09:44:57.653227793 2017: pid 2801: thd-0x1196c00: amidxtaped: pid 2801 finish time Wed Apr 12 09:44:57 2017





    What could be the problem?

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •