Amanda-Users

incremental backup not working

2006-06-06 19:09:18
Subject: incremental backup not working
From: "Mario Lobo" <mario.lobo AT ipad.com DOT br>
To: amanda-users AT amanda DOT org
Date: Tue, 06 Jun 2006 19:07:10 -0300
Hi to all; 

Forgive me for such a long post but  I needed to post as much info I had so 
you guys could have a better picture of what is going on. The logs and config 
files bellow will detail the full story. 

Short story: 
Fisrt full backup of the cycle works great, down to the last byte !! 
First incremental backup of the cycle, only 3 clients work, all the others fail 
:(. 

No firewall issues involved. The server and the clients are open at the 
firewall 
to see each other, otherwise, how would the full backup work, right?. And I 
checked 
if the firewall rules were there, before running the incremental. 

Well, my questions are spread throughout the info bellow. I´ve been learning 
and experimenting with 
AMANDA for more than 20 days straight and I´ve looked all the docs, wiki, 
google, etc.. and I don´t 
know where else to look for info that could help me fix this. 

Thank you before-hand for your help. 

Mario Lobo 

[SETUP]=========================================== 

1 Server machine: backup 

9 Client machines: backup, dtwmaster, intranet, jaboatao, moreno, 
                   olinda, recife, servidor, spyket-lab   

Obs - The backup server is backing up a windows share, mounted on itself. 

[amanda.conf ]========================================= 

dumpuser "amanda" 
inparallel 5 
dumporder "SSSSS" 
taperalgo largestfit 
displayunit "k" 
netusage 100000 
maxdumps 1 
dumpcycle 7 
runspercycle 5     
tapecycle 6 
runtapes 6 
tapebufs 30 
tapetype HARD-DISK 
tpchanger "chg-disk" 
changerfile "/amanda/test/changer" 
tapedev  "file:/bck/daily" 
maxdumpsize -1 
labelstr "^Tape[0-9][0-9]*$" 
infofile "/amanda/test/curinfo" 
logdir   "/amanda/test" 
indexdir "/amanda/test/index" 
tapelist "/amanda/test/tapelist" 
bumpsize 20 
bumppercent 20 
bumpdays 1 
bumpmult 4 
etimeout 350 
dtimeout 1800 
amrecover_do_fsf yes 
amrecover_check_label yes 
amrecover_changer "changer" 
ctimeout 40 
autoflush yes 
reserve 5 
holdingdisk ad1s1d { 
    comment "main holding disk" 
    directory "/amanda/hold" 
    use 95000 Mb 
    chunksize 0 
} 

define tapetype HARD-DISK { 
        comment "Dump onto hard disk" 
        length 57344 mbytes 
} 

define dumptype global { 
    comment "Global definitions" 
    index yes 
    record yes 
    holdingdisk yes 
} 

define dumptype full-unix { 
    global 
    program "GNUTAR" 
    comment "FULL dump with tar and compression" 
    compress server fast 
    priority high 
    exclude list "/usr/amanda/exclude" 
    fallback_splitsize 128m 
} 

define dumptype full-noex { 
    global 
    program "GNUTAR" 
    comment "FULL dump with tar and compression but no exclusions" 
    compress server fast 
    priority high 
    fallback_splitsize 128m 
} 

define dumptype full-windows { 
        global 
        program "GNUTAR" 
           comment "FULL Windows dump with tar and no compression" 
           options no-compress 
           priority high 
       fallback_splitsize 128m 
} 


[disklist - just a sample of each client. All disks are bellow on the amstatus 
listing]== 

olinda /etc full-noex 
... 
recife /etc full-unix 
... 
intranet /etc full-unix 
... 
servidor /etc full-unix 
... 
dtwmaster /etc full-unix 
... 
spyket-lab /etc full-unix 
... 
moreno /etc full-unix 
... 
jaboatao /etc full-unix 
... 
backup /gravata { 
        full-windows 
       estimate calcsize 
} 



[FULL BACKUP - success ]================================== 

*** running su - amanda -c "amdump test" 

The backup starts at 16:04:35 and the last dump is finished at 20:04:52. 


Using /amanda/test/fulldump.2 from Fri Jun  2 16:04:35 BRT 2006 

backup:/gravata     0  1067910k finished (20:01:28), PARTIAL (minor glitch, 
ignore please) 

dtwmaster:/db                                    0   870343k finished 
(16:24:17) 
dtwmaster:/etc                                   0      316k finished 
(17:41:15) 
dtwmaster:/root                                  0    62193k finished 
(17:41:08) 
dtwmaster:/usr/local                             0     3002k finished 
(17:41:14) 
dtwmaster:/usr/src                               0   111845k finished 
(17:41:06) 
dtwmaster:/var/db/pkg                         0      987k finished (17:41:15) 
dtwmaster:/var/db/ports                       0        1k finished (17:41:16) 
dtwmaster:/var/net-snmp                      0        1k finished (17:41:16) 

intranet:/etc                                       0     2459k finished 
(18:50:39) 
intranet:/home                                    0 20714434k finished 
(18:50:15) 
intranet:/spyket/rh8                             0   483550k finished 
(18:50:39) 
intranet:/var/lib/mysql                           0      341k finished 
(18:50:39) 
intranet:/var/net-snmp                          0        1k finished (18:50:39) 

jaboatao:/etc                                    0      810k finished 
(17:41:15) 
jaboatao:/root                                   0    18484k finished 
(17:41:12) 
jaboatao:/var/named                          0        3k finished (17:41:16) 

moreno:/backup                                0   738159k finished (16:37:26) 
moreno:/bd                                      0   853576k finished (17:35:10) 
moreno:/etc                                     0      259k finished (17:41:15) 
moreno:/home                                  0   133091k finished (17:40:59) 
moreno:/root                                    0     7444k finished (17:41:13) 
moreno:/var/db/pkg                           0      692k finished (17:41:15) 
moreno:/var/db/ports                         0        1k finished (17:41:16) 

olinda:/etc                                    0      397k finished (17:41:15) 
olinda:/home                                 0  7123376k finished (17:11:56) 
olinda:/root                                   0     5229k finished (17:41:14) 
olinda:/usr/local                              0   247688k finished (17:40:31) 
olinda:/usr/src                                0   139929k finished (17:40:51) 
olinda:/var/backups                         0        7k finished (17:41:16) 
olinda:/var/db/pkg                          0     1273k finished (17:41:15) 
olinda:/var/db/ports                        0        2k finished (17:41:16) 
olinda:/var/lib                                 0    43895k finished (17:41:10) 
olinda:/var/named                          0       11k finished (17:41:15) 
olinda:/var/net-snmp                      0        1k finished (17:41:16) 
olinda:/var/tomcat4                        0     2395k finished (17:41:14) 
olinda:/var/www                            0    31774k finished (17:41:11) 

recife:/etc                                    0     1955k finished (17:41:15) 
recife:/home                                 0 11059547k finished (17:33:56) 
recife:/majordomo-1.94.5               0      364k finished (17:41:15) 
recife:/root                                  0   204382k finished (17:40:42) 
recife:/var/lib/pgsql                       0     2043k finished (17:41:14) 
recife:/var/named                         0        7k finished (17:41:15) 
recife:/var/net-snmp                     0        1k finished (17:41:16) 
recife:/var/www                          0     3369k finished (17:41:14) 

servidor:/etc                               0     2272k finished (20:01:28) 
servidor:/home                            0  3267525k finished (20:04:52) 
servidor:/projetos                        0 46555775k finished (19:53:19) 
servidor:/root                             0     3149k finished (20:01:28) 
servidor:/temporario/mp3             0  9696254k finished (20:00:39) 
servidor:/var/net-snmp                 0        1k finished (20:01:28) 

spyket-lab:/etc                                     0     2635k finished 
(17:41:14) 
spyket-lab:/spyket/Fedora.good.no_sg     0     8262k finished (17:41:13) 
spyket-lab:/spyket/Fedora.klips-2.6.13.4_Os-2.4.0 0   450615k finished 
(17:38:18) 
spyket-lab:/spyket/Fedora.klips-2.6.14.4_Os-2.4.4 0   454662k finished 
(17:37:38) 
spyket-lab:/spyket/Fedora.netkey                       0   608908k finished 
(17:36:45) 
spyket-lab:/spyket/rh8                                      0   393366k 
finished (17:40:17) 
spyket-lab:/spyket/v1.5                                     0   448594k 
finished (17:38:46) 
spyket-lab:/spyket/v2                                        0   446636k 
finished (17:39:41) 
spyket-lab:/spyket/vElogica                                0   448590k finished 
(17:39:19) 

SUMMARY          part      real  estimated 
                                    size       size 
partition            :  60 
estimated          :  60             85716337k 
flush                 :   0         0k 
failed                 :   0                    0k           (  0.00%) 
wait for dumping :   0                    0k           (  0.00%) 
dumping to tape :   0                    0k           (  0.00%) 
dumping            :   0         0k         0k (  0.00%) (  0.00%) 
dumped             :  60 106724791k  85716337k (124.51%) (124.51%) 
wait for writing   :   0         0k         0k (  0.00%) (  0.00%) 
wait to flush       :   0         0k         0k (100.00%) (  0.00%) 
writing to tape   :   0         0k         0k (  0.00%) (  0.00%) 
failed to tape     :   0         0k         0k (  0.00%) (  0.00%) 
taped               :  60 106724791k  85716337k (124.51%) (124.51%) 
5 dumpers idle   : no-dumpers 
taper idle 
network free kps:    100000 
holding space     :  97280000k (100.00%) 
chunker0 busy   :  0:00:00  (  0.00%) 
chunker1 busy   :  0:00:00  (  0.00%) 
chunker2 busy   :  0:00:00  (  0.00%) 
chunker3 busy   :  0:00:00  (  0.00%) 
chunker4 busy   :  0:00:01  (  0.01%) 
 dumper0 busy   :  3:10:40  ( 79.35%) 
 dumper1 busy   :  2:05:56  ( 52.42%) 
 dumper2 busy   :  2:24:47  ( 60.25%) 
 dumper3 busy   :  1:57:26  ( 48.87%) 
 dumper4 busy   :  0:56:56  ( 23.70%) 
   taper busy      :  3:03:16  ( 76.27%) 
 0 dumpers busy :  0:44:02  ( 18.33%)         no-dumpers:  0:32:14  ( 73.20%) 
                                                                    not-idle:  
0:11:48  ( 26.80%) 
 1 dumper busy  :  0:25:36  ( 10.66%)           no-dumpers:  0:14:19  ( 55.97%) 
                                                         client-constrained:  
0:10:52  ( 42.50%) 
                                                                    start-wait: 
 0:00:23  (  1.53%) 
 2 dumpers busy :  0:57:54  ( 24.10%)   client-constrained:  0:57:54  (100.00%) 
 3 dumpers busy :  1:04:41  ( 26.92%)   client-constrained:  1:04:41  (100.00%) 
 4 dumpers busy :  0:27:13  ( 11.33%)   client-constrained:  0:13:10  ( 48.42%) 
                                                                    start-wait: 
 0:07:54  ( 29.03%) 
                                                                 no-dumpers:  
0:06:08  ( 22.56%) 
 5 dumpers busy :  0:20:48  (  8.66%)    client-constrained:  0:20:48  ( 
99.99%) 


*** As you can see, it backs everything up perfectly. I checked each one of 
them 
      with amrecover and everything is there. 



[INCREMENTAL BACKUP - failure ]============================== 

*** running  `su - amanda -c "amdump test"`, a day after the full backup, 
without 
      doing ANYTHING between the two runs.         

*** All level 0 backups here were supposed to be level 1! What could be causing 
this? 



Using /amanda/test/incrdump.1 from Sat Jun  3 18:49:58 BRT 2006 

backup:/gravata      (level 1 correct !)         1      520k finished 
(19:00:36) 

dtwmaster:/db    (level 0 !! why ??)     0 planner: [hmm, disk was stranded on 
waitq] 
dtwmaster:/etc                 "               0 planner: [hmm, disk was 
stranded on waitq] 
dtwmaster:/root                "               0 planner: [hmm, disk was 
stranded on waitq] 
dtwmaster:/usr/local           "               0 planner: [hmm, disk was 
stranded on waitq] 
dtwmaster:/usr/src             "               0 planner: [hmm, disk was 
stranded on waitq] 
dtwmaster:/var/db/pkg       "               0 planner: [hmm, disk was stranded 
on waitq] 
dtwmaster:/var/db/ports     "               0 planner: [hmm, disk was stranded 
on waitq] 
dtwmaster:/var/net-snmp    "               0 planner: [hmm, disk was stranded 
on waitq] 

                                                             *** How can a disk 
be stranded on an 
                                                                   incremental 
and not on a full? 
                                                             *** What does this 
message mean? 
                                                                   Could not 
find anything on it on 
docs! 
        
intranet:/etc             (level 1 correct !)          1       71k finished 
(20:44:43) 
intranet:/home (level 0 ! why promoted?)       0 33014946k finished (20:44:43) 
intranet:/spyket/rh8       (level 1 correct !)      1       80k finished 
(20:44:43) 
intranet:/var/lib/mysql           "                       1        1k finished 
(20:44:43) 
intranet:/var/net-snmp            "                    1        1k finished 
(20:44:43) 

jaboatao:/etc           (level 1 correct !)      1        9k finished 
(19:00:25) 
jaboatao:/root                  "                    1        2k finished 
(19:00:55) 
jaboatao:/var/named             "                1        1k finished 
(19:00:38) 

moreno:/backup (level 0 !! why ??)       0 planner: [hmm, disk was stranded on 
waitq] 
moreno:/bd                    "                  0 planner: [hmm, disk was 
stranded on waitq] 
moreno:/etc                   "                  0 planner: [hmm, disk was 
stranded on waitq] 
moreno:/home                "                  0 planner: [hmm, disk was 
stranded on waitq] 
moreno:/root                  "                  0 planner: [hmm, disk was 
stranded on waitq] 
moreno:/var/db/pkg         "                  0 planner: [hmm, disk was 
stranded on waitq] 
moreno:/var/db/ports       "                  0 planner: [hmm, disk was 
stranded on waitq] 

olinda:/etc        (level 0 !! why ??)       0 planner: [hmm, disk was stranded 
on waitq] 
olinda:/home                 "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/root                   "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/usr/local              "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/usr/src                "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/backups         "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/db/pkg          "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/db/ports        "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/lib                 "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/named          "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/net-snmp      "                  0 planner: [hmm, disk was stranded 
on waitq] 
olinda:/var/tomcat4        "                  0 planner: [hmm, disk was 
stranded on waitq] 
olinda:/var/www            "                  0 planner: [hmm, disk was 
stranded on waitq] 

recife:/etc        (level 0 !! why ??)       0 planner: [hmm, disk was stranded 
on waitq] 
recife:/home                 "                  0 planner: [hmm, disk was 
stranded on waitq] 
recife:/majordomo-1.94.5   "               0 planner: [hmm, disk was stranded 
on waitq] 
recife:/root                   "                  0 planner: [hmm, disk was 
stranded on waitq] 
recife:/var/lib/pgsql        "                  0 planner: [hmm, disk was 
stranded on waitq] 
recife:/var/named          "                  0 planner: [hmm, disk was 
stranded on waitq] 
recife:/var/net-snmp      "                  0 planner: [hmm, disk was stranded 
on waitq] 
recife:/var/www           "                  0 planner: [hmm, disk was stranded 
on waitq] 

servidor:/etc   (level 0 !! why ??)       0 planner: [hmm, disk was stranded on 
waitq] 
servidor:/home     "                         0 planner: [hmm, disk was stranded 
on waitq] 
servidor:/projetos  "                         0 planner: [hmm, disk was 
stranded on waitq] 
servidor:/root       "                         0 planner: [hmm, disk was 
stranded on waitq] 
servidor:/temporario/mp3      "          0 planner: [hmm, disk was stranded on 
waitq] 
servidor:/var/net-snmp        "           0 planner: [hmm, disk was stranded on 
waitq] 

spyket-lab:/etc       (level 0 !! why ??) 0 planner: [hmm, disk was stranded on 
waitq] 
spyket-lab:/spyket/Fedora.good.no_sg " 0 planner: [hmm, disk was stranded on 
waitq] 
spyket-lab:/spyket/Fedora.klip..._          0 planner: [hmm, disk was stranded 
on waitq] 
spyket-lab:/spyket/Fedora.klip...2.4.4     0 planner: [hmm, disk was stranded 
on waitq] 
spyket-lab:/spyket/Fedora.netkey "        0 planner: [hmm, disk was stranded on 
waitq] 
spyket-lab:/spyket/rh8               "         0 planner: [hmm, disk was 
stranded on waitq] 
spyket-lab:/spyket/v1.5             "          0 planner: [hmm, disk was 
stranded on waitq] 
spyket-lab:/spyket/v2               "          0 planner: [hmm, disk was 
stranded on waitq] 
spyket-lab:/spyket/vElogica       "          0 planner: [hmm, disk was stranded 
on waitq] 

SUMMARY          part      real  estimated 
                                    size       size 
partition            :  60 
estimated          :   9             28467043k 
flush                 :   0                  0k 
failed                :  51                  0k           (  0.00%) 
wait for dumping:   0                   0k           (  0.00%) 
dumping to tape:   0                   0k           (  0.00%) 
dumping           :   0         0k         0k (  0.00%) (  0.00%) 
dumped            :   9  33015631k  28467043k (115.98%) (115.98%) 
wait for writing   :   0         0k         0k (  0.00%) (  0.00%) 
wait to flush       :   0         0k         0k (100.00%) (  0.00%) 
writing to tape   :   0         0k         0k (  0.00%) (  0.00%) 
failed to tape     :   0         0k         0k (  0.00%) (  0.00%) 
taped               :   9  33015631k  28467043k (115.98%) (115.98%) 
5 dumpers idle   : no-dumpers 
taper idle 
network free kps:    100000 
holding space     :  97280000k (100.00%) 
chunker0 busy    :  0:00:00  (  0.00%) 
chunker1 busy    :  0:00:00  (  0.00%) 
chunker2 busy    :  0:00:00  (  0.00%) 
 dumper0 busy    :  1:28:49  ( 77.40%) 
 dumper1 busy    :  0:00:13  (  0.20%) 
 dumper2 busy    :  0:00:01  (  0.02%) 
   taper busy       :  0:15:37  ( 13.61%) 
 0 dumpers busy  :  0:26:04  ( 22.73%)         no-dumpers:  0:14:52  ( 57.05%) 
                                                                      not-idle: 
 0:10:27  ( 40.07%) 
                                                                    start-wait: 
 0:00:39  (  2.50%) 
                                                         client-constrained:  
0:00:06  (  0.38%) 
 1 dumper busy  :  1:28:29  ( 77.11%)   client-constrained:  1:28:27  ( 99.96%) 
                                                                    start-wait: 
 0:00:02  (  0.04%) 
 2 dumpers busy :  0:00:11  (  0.16%)               start-wait:  0:00:11  ( 
99.98%) 
 3 dumpers busy :  0:00:00  (  0.00%) 


[LOG FILES]====================================== 

------------------------------------------------------------------ 
Lines taken from the SERVER log file during the incremental backup 
------------------------------------------------------------------ 

START driver date 20060603 
DISK planner olinda /etc 
DISK planner olinda /root 
DISK planner olinda /usr/local 
DISK planner olinda /usr/src 
DISK planner olinda /home 
DISK planner olinda /var/lib 
 (and so on for the other clients ...) 

START planner date 20060603 
STATS driver startup time 0.024 
START taper datestamp 20060603 label Tape4 tape 0 
ERROR planner Request to servidor failed: timeout waiting for ACK 
ERROR planner Request to recife failed: timeout waiting for ACK 
ERROR planner Request to spyket-lab failed: timeout waiting for ACK 
ERROR planner Request to moreno failed: timeout waiting for ACK 
ERROR planner Request to olinda failed: timeout waiting for ACK 
ERROR planner Request to dtwmaster failed: timeout waiting for ACK 

FAIL planner dtwmaster /var/net-snmp 20060603 0 [hmm, disk was stranded on 
waitq] 
FAIL planner dtwmaster /var/db/ports 20060603 0 [hmm, disk was stranded on 
waitq] 
FAIL planner dtwmaster /var/db/pkg 20060603 0 [hmm, disk was stranded on waitq] 
FAIL planner dtwmaster /usr/src 20060603 0 [hmm, disk was stranded on waitq] 
 (and so on for the ERROR clients ...) 

INFO planner Full dump of intranet:/home promoted from 6 days ahead. 
***  why did the above happen? 
FINISH planner date 20060603 time 625.911 

SUCCESS dumper backup /gravata 20060603 1 [sec 12.556 kb 520 kps 41.4 orig-kb 
520] 
SUCCESS chunker backup /gravata 20060603 1 [sec 12.568 kb 520 kps 43.9] 
SUCCESS taper backup /gravata 20060603 1 [sec 0.022 kb 576 kps 25808.8 {wr: 
writers 18 rdwait 0.001 
wrwait 0.020 filemark 0.000}] 
 (and so on for the other SUCCESS clients ...) 

INFO taper tape Tape4 kb 33016160 fm 9 [OK] 
FINISH driver date 20060603 time 6885.965 

------------------------------------------------------------------ 
Lines taken from the amandad.xxx.debug file, 
from one of the clients (recife) during the SUCCESSFUL full backup 
------------------------------------------------------------------ 

amandad: debug 1 pid 4040 ruid 11026 euid 11026: start at Fri Jun  2 17:23:35 
2006 
amandad: version 2.5.0p2 
amandad: build: VERSION="Amanda-2.5.0p2" 
amandad:        BUILT_DATE="Sun May 21 14:31:04 BRT 2006" 
amandad:        BUILT_MACH="Linux spyket-lab 2.6.13.4 #10 Tue Nov 1 11:03:01 
BRT 2005 i686 i686 
i386 GNU/Linux" 
amandad:        CC="gcc" 
amandad:        CONFIGURE_COMMAND="'./configure' 
'--libexecdir=/usr/local/libexec/amanda' '--with-
amandahosts' '--with-fqdn' '--with-dump-honor-nodump' '--with-buffered-dump' 
'--disable-libtool' '--
prefix=/usr/local' '--with-user=amandabck' '--with-group=amandabck' 
'--with-gnutar-
listdir=/usr/local/var/amanda/gnutar-lists' '--with-gnutar=/bin/gtar' 
'--without-server' '--
prefix=/usr/local'" 
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin" 
amandad:        libexecdir="/usr/local/libexec/amanda" 
amandad:        mandir="/usr/local/man" AMANDA_TMPDIR="/tmp/amanda" 
amandad:        AMANDA_DBGDIR="/tmp/amanda" 
amandad:        CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/" 
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump" 
amandad:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF 
amandad:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF 
amandad:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/gtar" 
amandad:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip" 
amandad:        LPRCMD="/usr/bin/lpr" MAILER="/usr/bin/Mail" 
amandad:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists" 
amandad: defs:  DEFAULT_SERVER="spyket-lab" 
amandad:        DEFAULT_CONFIG="DailySet1" 
amandad:        DEFAULT_TAPE_SERVER="spyket-lab" 
amandad:        DEFAULT_TAPE_DEVICE="null:" HAVE_MMAP HAVE_SYSVSHM 
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE 
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS 
amandad:        CLIENT_LOGIN="amandabck" FORCE_USERID HAVE_GZIP 
amandad:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast" 
amandad:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc" 
amandad: time 0.052: accept recv REQ pkt: 
<<<<< 
SERVICE sendbackup 
OPTIONS features=fffffeff9ffeffff07;hostname=recife; 
GNUTAR /var/net-snmp  0 1970:1:1:0:0:0 OPTIONS 
|;auth=BSD;srvcomp-fast;index;exclude-
list=/usr/amanda/exclude; 
>>>>> 
amandad: time 0.053: creating new service: /usr/local/libexec/amanda/sendbackup 
OPTIONS features=fffffeff9ffeffff07;hostname=recife; 
GNUTAR /var/net-snmp  0 1970:1:1:0:0:0 OPTIONS 
|;auth=BSD;srvcomp-fast;index;exclude-
list=/usr/amanda/exclude; 

amandad: time 0.058: sending ACK pkt: 
<<<<< 
>>>>> 
amandad: try_socksize: send buffer size is 65536 
amandad: try_socksize: receive buffer size is 65536 
amandad: time 0.080: bind_portrange2: trying port=527 

 ( and so on.....) 



--------------------------------------------------------------------- 
Lines taken from the amandad.xxx.debug file, 
from one of the FAILed clients (recife) during the FAILED incremental backup 
--------------------------------------------------------------------- 

*** This is the full log of the incremental session !! 

amandad: debug 1 pid 21199 ruid 11026 euid 11026: start at Sat Jun  3 19:09:07 
2006 
amandad: version 2.5.0p2 
amandad: build: VERSION="Amanda-2.5.0p2" 
amandad:        BUILT_DATE="Sun May 21 14:31:04 BRT 2006" 
amandad:        BUILT_MACH="Linux spyket-lab 2.6.13.4 #10 Tue Nov 1 11:03:01 
BRT 2005 i686 i686 
i386 GNU/Linux" 
amandad:        CC="gcc" 
amandad:        CONFIGURE_COMMAND="'./configure' 
'--libexecdir=/usr/local/libexec/amanda' '--with-
amandahosts' '--with-fqdn' '--with-dump-honor-nodump' '--with-buffered-dump' 
'--disable-libtool' '--
prefix=/usr/local' '--with-user=amandabck' '--with-group=amandabck' 
'--with-gnutar-
listdir=/usr/local/var/amanda/gnutar-lists' '--with-gnutar=/bin/gtar' 
'--without-server' '--
prefix=/usr/local'" 
amandad: paths: bindir="/usr/local/bin" sbindir="/usr/local/sbin" 
amandad:        libexecdir="/usr/local/libexec/amanda" 
amandad:        mandir="/usr/local/man" AMANDA_TMPDIR="/tmp/amanda" 
amandad:        AMANDA_DBGDIR="/tmp/amanda" 
amandad:        CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/" 
amandad:        RDEV_PREFIX="/dev/r" DUMP="/sbin/dump" 
amandad:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF 
amandad:        XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF 
amandad:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/gtar" 
amandad:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip" 
amandad:        LPRCMD="/usr/bin/lpr" MAILER="/usr/bin/Mail" 
amandad:        listed_incr_dir="/usr/local/var/amanda/gnutar-lists" 
amandad: defs:  DEFAULT_SERVER="spyket-lab" 
amandad:        DEFAULT_CONFIG="DailySet1" 
amandad:        DEFAULT_TAPE_SERVER="spyket-lab" 
amandad:        DEFAULT_TAPE_DEVICE="null:" HAVE_MMAP HAVE_SYSVSHM 
amandad:        LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE 
amandad:        AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS 
amandad:        CLIENT_LOGIN="amandabck" FORCE_USERID HAVE_GZIP 
amandad:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast" 
amandad:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc" 
amandad: time 0.002: accept recv REQ pkt: 
<<<<< 
SERVICE noop 
OPTIONS features=fffffeff9ffeffff07; 
>>>>> 
amandad: time 0.002: creating new service: /usr/local/libexec/amanda/noop 
OPTIONS features=fffffeff9ffeffff07; 

amandad: time 0.005: sending ACK pkt: 
<<<<< 
>>>>> 
amandad: time 0.006: sending REP pkt: 
<<<<< 
OPTIONS features=fffffeff9ffeffff07; 
>>>>> 
amandad: time 0.006: received ACK pkt: 
<<<<< 
>>>>> 
amandad: time 29.999: pid 21199 finish time Sat Jun  3 19:09:37 2006 

===================================================== 

*** Why does it start the noop SERVICE on the incremental, 
    instead of the sendbackup SERVICE,like in the full dump? 

Thanks for reading this far :) 

Mario
-- 
   //|  //||
  // | // ||
-//--//---|| ARIO LOBO
//  //    ||
---------------------------------
mario.lobo AT ipad.com DOT br
http://www.ipad.com.br



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