I have just installed 3.1.2 on a FreeBSD 8.1 system. I have used
various versions over the years, so the installation went well. I
tried to follow the directions for setting up a virtual tape library.
To start, I am just trying to backup a single file system from the
backup server itself.
I can start manually start an 'amdump daily'. Watching the various
logs, the planner seems to run okay, and the dump files are being
received and written to the holding disk.
It appears that the taper never gets the data streams. The amdump
output is below. Everything looks okay to me until the send-cmd line
where it talks to the first (dumper0). The following seems quite out
of place as part of a send-cmd
|" <auth>BSD</auth>\n <compress>FAST</compress>\n
<record>YES</record>\n <index>YES</index>\n
<datapath>AMANDA</datapath>\n"
I would appreciate any fixes or suggestions.
============================================================================
SETTING UP FOR ESTIMATES...
planner: time 0.072: setting up estimates for galadriel.corbesero.net:/
setup_estimate: galadriel.corbesero.net:/: command 0, options: none
last_level 2 next_level0 27 level_days 1 getting estimates 0 (-3) 2 (-3) -1
(-3)
planner: time 0.121: setting up estimates took 0.048 secs
GETTING ESTIMATES...
dumper: pid 4466 executable dumper0 version 3.1.2
dumper: pid 4469 executable dumper3 version 3.1.2
dumper: pid 4467 executable dumper1 version 3.1.2
dumper: pid 4468 executable dumper2 version 3.1.2
driver: result time 5.347 from taper: TAPER-OK
driver: state time 5.348 free kps: 8000 space: 16259072 taper: idle
idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 5.348 if default: free 8000
driver: hdisk-state time 5.348 hdisk 0: free 2129920 dumpers 0 hdisk 1: free
14129152 dumpers 0
planner: time 6.362: got partial result for host galadriel.corbesero.net disk
/: 0 -> -3K, 2 -> -3K, -1 -> -3K
planner: time 7.869: got partial result for host galadriel.corbesero.net disk
/: 0 -> 332574K, 2 -> -3K, -1 -> -3K
planner: time 9.488: got partial result for host galadriel.corbesero.net disk
/: 0 -> 332574K, 2 -> 136K, -1 -> -3K
planner: time 10.494: got result for host galadriel.corbesero.net disk /: 0 ->
332574K, 2 -> 136K, -1 -> -3K
planner: time 10.494: getting estimates took 10.373 secs
FAILED QUEUE: empty
DONE QUEUE:
0: galadriel.corbesero.net /
ANALYZING ESTIMATES...
pondering galadriel.corbesero.net:/... next_level0 27 last_level 2 (not due for
a full dump, picking an incr level)
pick: size 136 level 2 days 1 (thresh 266059K, 1 days)
curr level 2 nsize 136 csize 34 total size 133 total_lev0 0 balanced-lev0size
7384
INITIAL SCHEDULE (size 133):
galadriel.corbesero.net / pri 0 lev 2 nsize 136 csize 34
DELAYING DUMPS IF NEEDED, total_size 133, tape length 9216000 mark 1
delay: Total size now 133.
PROMOTING DUMPS IF NEEDED, total_lev0 0, balanced_size 7384...
planner: time 10.501: analysis took 0.004 secs
GENERATING SCHEDULE:
--------
DUMP galadriel.corbesero.net ffffffff9efefbffffffffff01 / 20100912102842 0 2
2010:9:12:3:7:7 136 34 0 855 "Skipping: a full is not planned, so can't dump in
degraded mode"
--------
driver: flush size 0
driver: started chunker0 pid 4518
driver: send-cmd time 10.579 to chunker0: START 20100912102842
driver: send-cmd time 10.602 to chunker0: PORT-WRITE 00-00001
/amanda/holding-b/20100912102842/galadriel.corbesero.net._.2
galadriel.corbesero.net ffffffff9efefbffffffffff01 / 2 2010:9:12:3:7:7 1048576
DUMP 128 |;auth=BSD;compress-fast;index;
chunker: pid 4518 executable chunker0 version 3.1.2
driver: result time 10.697 from chunker0: PORT 2379 127.0.0.1:2380
driver: send-cmd time 10.697 to dumper0: PORT-DUMP 00-00001 2379
galadriel.corbesero.net ffffffff9efefbffffffffff01 / NODEVICE 2 2010:9:12:3:7:7
DUMP "" "" "" "" BSD AMANDA 127.0.0.1:2380 |" <auth>BSD</auth>\n
<compress>FAST</compress>\n <record>YES</record>\n <index>YES</index>\n
<datapath>AMANDA</datapath>\n"
driver: state time 10.698 free kps: 7145 space: 16258944 taper: idle
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 10.698 if default: free 7145
driver: hdisk-state time 10.698 hdisk 0: free 2129920 dumpers 0 hdisk 1: free
14129024 dumpers 1
driver: state time 22.477 free kps: 7145 space: 16258944 taper: idle
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 22.477 if default: free 7145
driver: hdisk-state time 22.477 hdisk 0: free 2129920 dumpers 0 hdisk 1: free
14129024 dumpers 1
driver: result time 22.477 from dumper0: DONE 00-00001 192 14 7 "[sec 6.703 kb
14 kps 2.1 orig-kb 192]"
driver: finished-cmd time 22.477 dumper0 dumped galadriel.corbesero.net:/
driver: send-cmd time 22.477 to chunker0: DONE 00-00001
driver: state time 22.505 free kps: 7145 space: 16258944 taper: idle
idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 22.505 if default: free 7145
driver: hdisk-state time 22.505 hdisk 0: free 2129920 dumpers 0 hdisk 1: free
14129024 dumpers 1
driver: result time 22.505 from chunker0: DONE 00-00001 14 "[sec 11.796 kb 14
kps 3.9]"
driver: finished-cmd time 22.505 chunker0 chunked galadriel.corbesero.net:/
driver: send-cmd time 22.514 to taper: FILE-WRITE 00-00002
/amanda/holding-b/20100912102842/galadriel.corbesero.net._.2
galadriel.corbesero.net / 2 20100912102842 0 192
driver: startaflush: FIRST galadriel.corbesero.net / 46 9216000
driver: state time 22.521 free kps: 8000 space: 16259026 taper: writing
idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 22.521 if default: free 8000
driver: hdisk-state time 22.521 hdisk 0: free 2129920 dumpers 0 hdisk 1: free
14129106 dumpers 0
--
Stephen Corbesero It's always darkest
Bethlehem, PA 18015 before pitch black.
corbes...@ptd.net
