Results 1 to 2 of 2

Thread: Long run time and insane compression ratio

  1. #1
    Join Date
    Oct 2012
    Location
    Portland, OR, USA
    Posts
    2

    Default Long run time and insane compression ratio

    This has happened a few times, and I'm not sure what's causing it. This is happening on my incremental dumps, which only take about two hours to complete normally. What could be causing this to happen?

    This particular run was done to a newly labelled tape, but I've seen it happen on tapes that have been in rotation for a while, so I think it's more dumper related than taper.

    Here's the summary mail from last night's run.

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

    Hostname: parrot
    Org : DailyBackup
    Config : DailySet1
    Date : October 31, 2012

    These dumps were to tape DailySet1-008.
    There are 110G of dumps left in the holding disk.
    Run amflush to flush them to tape.

    The next tape Amanda expects to use is: DailySet1-001.

    FAILURE DUMP SUMMARY:
    parrot /kvm lev 0 FAILED "/usr/lib64/amanda/runtar exited with status 2: see /var/log/amanda/client/DailySet1/sendsize.20121031004503.debug"
    parrot / lev 0 FAILED "/usr/lib64/amanda/runtar exited with status 1: see /var/log/amanda/client/DailySet1/sendsize.20121031004503.debug"
    parrot /kvm lev 1 FAILED [dump (9607) /bin/tar returned 2]
    parrot /kvm lev 1 FAILED [dump (21267) /bin/tar returned 2]
    roughy / RESULTS MISSING
    planner: ERROR Request to roughy failed: No route to host
    parrot /kvm lev 1: partial taper:

    STRANGE DUMP SUMMARY:
    parrot / lev 1 STRANGE (see below)


    STATISTICS:
    Total Full Incr.
    -------- -------- --------
    Estimate Time (hrs:min) 0:06
    Run Time (hrs:min) 7:37
    Dump Time (hrs:min) 0:09 0:00 0:09
    Output Size (meg) 461584.9 0.0 461584.9
    Original Size (meg) 11662.9 0.0 11662.9
    Avg Compressed Size (%) 3958 -- 3958 (level:#disks ...)
    Filesystems Dumped 17 0 17 (1:17)
    Avg Dump Rate (k/s) 854343 -- 854343

    Tape Time (hrs:min) 0:53 0:00 0:53
    Tape Size (meg) 233760.0 0.0 233760.0
    Tape Used (%) 30.3 0.0 30.3 (level:#disks ...)
    Filesystems Taped 18 0 18 (1:18)
    (level:#chunks ...)
    Chunks Taped 18 0 18 (1:18)
    Avg Tp Write Rate (k/s) 74926.4 -- 74926.4

    USAGE BY TAPE:
    Label Time Size % Nb Nc
    DailySet1-008 0:53 228G 30.3 18 18


    FAILED DUMP DETAILS:

    /-- parrot /kvm lev 1 FAILED [dump (9607) /bin/tar returned 2]
    sendbackup: start [parrot:/kvm level 1]
    sendbackup: info BACKUP=/bin/tar
    sendbackup: info RECOVER_CMD=/usr/bin/gzip -dc |/bin/tar -xpGf - ...
    sendbackup: info COMPRESS_SUFFIX=.gz
    sendbackup: info end
    ? /bin/tar: ./dumps/amanda/20121031004502/sauce._.1: File removed before we read it
    ? /bin/tar: ./dumps/amanda/20121031004502/sauce._.1.1: File removed before we read it
    ? /bin/tar: ./dumps/amanda/20121031004502/sauce._.1.2: File removed before we read it
    ? /bin/tar: ./dumps/amanda/20121031004502/sauce._.1.3: File removed before we read it
    ? /bin/tar: ./images/stick.img: file changed as we read it
    ? /bin/tar: ./images/roughy.img: file changed as we read it
    ? /bin/tar: ./images/green.img: file changed as we read it
    ? /bin/tar: ./images/honeydew.img: file changed as we read it
    ? /bin/tar: ./images/slimer.img: file changed as we read it
    ? /bin/tar: ./images/spice.img: file changed as we read it
    ? /bin/tar: value 50942870016 out of off_t range 0..8589934591
    ? /bin/tar: ./images/sauce.img: file changed as we read it
    | Total bytes written: 357722183680 (334GiB, 30MiB/s)
    ? /bin/tar: Exiting with failure status due to previous errors
    ? dump (9607) /bin/tar returned 2
    sendbackup: error [dump (9607) /bin/tar returned 2]
    \--------

    /-- parrot /kvm lev 1 FAILED [dump (21267) /bin/tar returned 2]
    sendbackup: start [parrot:/kvm level 1]
    sendbackup: info BACKUP=/bin/tar
    sendbackup: info RECOVER_CMD=/usr/bin/gzip -dc |/bin/tar -xpGf - ...
    sendbackup: info COMPRESS_SUFFIX=.gz
    sendbackup: info end
    ? /bin/tar: ./images/stick.img: file changed as we read it
    ? /bin/tar: ./images/roughy.img: file changed as we read it
    ? /bin/tar: ./images/green.img: file changed as we read it
    ? /bin/tar: ./images/honeydew.img: file changed as we read it
    ? /bin/tar: ./images/slimer.img: file changed as we read it
    ? /bin/tar: ./images/spice.img: file changed as we read it
    ? /bin/tar: value 50943002112 out of off_t range 0..8589934591
    ? /bin/tar: ./images/sauce.img: file changed as we read it
    | Total bytes written: 357722378240 (334GiB, 29MiB/s)
    ? /bin/tar: Exiting with failure status due to previous errors
    ? dump (21267) /bin/tar returned 2
    sendbackup: error [dump (21267) /bin/tar returned 2]
    \--------


    STRANGE DUMP DETAILS:

    /-- parrot / lev 1 STRANGE
    sendbackup: start [parrot:/ level 1]
    sendbackup: info BACKUP=/bin/tar
    sendbackup: info RECOVER_CMD=/usr/bin/gzip -dc |/bin/tar -xpGf - ...
    sendbackup: info COMPRESS_SUFFIX=.gz
    sendbackup: info end
    ? /bin/tar: ./etc/amanda/DailySet1/index/chomper/_/20121031004502_1.gz.tmp: File removed before we read it
    | /bin/tar: ./var/lib/libvirt/qemu/sauce.monitor: socket ignored
    | Total bytes written: 148469760 (142MiB, 7.7MiB/s)
    sendbackup: size 144990
    sendbackup: end
    \--------


    NOTES:
    planner: Adding new disk roughy:/.
    taper: tape DailySet1-008 kb 239370202 fm 18 [OK]
    small estimate: sauce / 1
    est: 2G out 4G
    small estimate: rv / 1
    est: 0G out 0G


    DUMP SUMMARY:
    DUMPER STATS TAPER STATS
    HOSTNAME DISK L ORIG-GB OUT-GB COMP% MMM:SS KB/s MMM:SS KB/s
    -------------------------- ------------------------------------- -------------
    parrot / MISSING -------------------------------------------
    parrot / 1 0 0 19.4 0:24 1195.4 0:00 83013.4
    parrot /home 1 0 0 62.2 0:58 2665.8 0:03 47136.0
    parrot /kvm MISSING -------------------------------------------
    parrot /kvm 1 222 -- PARTIAL 51:47 75082.0 PARTIAL
    stick / 1 0 0 18.0 0:08 1511.4 0:00 83211.6
    rv / 1 1 0 71.0 0:36 14404.7 0:10 50115.9
    bigbird / 1 0 0 13.9 0:07 528.0 0:00 62580.9
    snuffy / 1 0 0 14.4 0:07 691.0 0:00 74976.7
    snuffy /data 1 1 0 34.6 0:31 14727.5 0:07 63433.2
    roughy / MISSING -------------------------------------------
    throat / 1 0 0 27.3 0:08 1364.4 0:00 76210.4
    green / 1 0 0 17.2 0:08 1434.8 0:00 76807.6
    honeydew / 1 0 0 28.9 0:13 5852.2 0:01 85587.2
    honeydew /data 1 3 1 20.9 1:05 10678.0 0:10 67661.8
    slimer / 1 0 0 18.4 0:08 1477.5 0:00 74588.7
    spice / 1 0 0 18.3 0:10 2173.2 0:00 83962.3
    flaky / 1 0 0 24.2 0:10 1256.6 0:00 77487.5
    bitey / 1 0 0 47.9 0:09 5212.7 0:01 82608.7
    sauce / 1 5 4 76.5 4:02 16496.7 0:53 74914.0
    chomper / 1 0 0 14.7 0:09 1718.6 0:00 81401.4

    (brought to you by Amanda version 2.6.1p2)

  2. #2
    Join Date
    Oct 2012
    Location
    Portland, OR, USA
    Posts
    2

    Default No takers?

    I'm having trouble finding related posts. Is anyone available to point me in the right direction?

Posting Permissions

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