Amanda-Users

Problems with Overland Library and Solaris 8

2004-10-11 03:37:02
Subject: Problems with Overland Library and Solaris 8
From: bwkstuttgart AT yahoo DOT de
To: amanda-users AT amanda DOT org
Date: Mon, 11 Oct 2004 09:26:39 +0200
Hi!

During the last two days I configured Amanda to work with a Overland 
Library (Overland LoaderXpress, 11 Slots, 1 LTO-1 drive) under SUN 
Solaris V8. After studying some threads here and on the web it was no 
problem to get the changer running (if someone is interested in doing 
it I can give a detailed explanation - feel free to ask!!).

All commands (like "amtape <config> slot next", "amtape <config> slot 
prev", "amcheck <config>) are working really fine. I hadn't have any 
errors.

My amanda.conf looks like this:

--- SNIP

dumpuser "amanda"
inparallel 4
dumporder "sssS"
dumpcycle 1 weeks
runspercycle 5
tapecycle 5 tapes
bumpsize 20 Mb
bumpdays 1
bumpmult 4
etimeout 300
dtimeout 1800
ctimeout 30
tapebufs 40
runtapes 2
tpchanger "chg-zd-mtx"
tapedev "/dev/rmt/1cn"
changerfile "/etc/amanda/edvchanger/chg-zd-mtx.conf"
changerdev "/dev/scsi/changer/c3t4d0"
maxdumpsize -1
tapetype LTO-1
labelstr "^sun-edv-[0-9][0-9][0-9]*$"
amrecover_do_fsf yes
amrecover_check_label yes
amrecover_changer "/dev/scsi/changer/c3t4d0"

holdingdisk hd1 {
    comment "main holding disk"
    directory "/bwk/amanda"
    use 30720 Mb
    chunksize 1Gb
    }

autoflush no

define tapetype LTO-1 {
    comment "LTO Gen 1 tape drive"
    length 101376 mbytes
    filemark 0 kbytes
    speed 15080 kbytes
}

define dumptype always-full {
    global
    comment "Full dump of this filesystem always"
    compress server fast
    priority high
    dumpcycle 0
}

--- SNAP

The "chg-zd-mtx.conf" looks like this:

--- SNIP

firstslot=0
lastslot=9
cleanslot=10

--- SNAP

The "disklist" looks like this:

--- SNIP

A /dev/sda5      always-full     # 1.5G
A /dev/sda6      always-full     # 224M
A /dev/sda8      always-full     # 355M

# EDVSERV
B /dev/sdb6       always-full     # 2.1G
B /dev/sda5       always-full     # 376M
B /dev/sda6       always-full     # 3.2G
B /dev/sda7       always-full     # 1.6G
B /dev/sda8       always-full     # 28G
B /dev/sda9       always-full     # 76G
B /dev/sda10      always-full     # 3.6G

--- SNAP

This means a total capacity for backup of round about 117G.
I thought, 2 tapes (specified in runtapes) should be enough and 
amanda would change the needed tapes automatically.

I started the first backup "amdump edvchanger".

It was horrible. The complete SUN-Server crashed!!
There was no response to pings and no chance to get a console over 
the rsc-board....

Here are some snippets out of the logfile "amdump":

--- SNIP

amdump: start at Sat Oct  9 13:41:09 MEST 2004
amdump: datestamp 20041009
driver: pid 14018 executable /usr/local/amanda/libexec/driver version 
2.4.4p2
driver: tape size 103809024
driver: send-cmd time 0.004 to taper: START-TAPER 20041009
driver: started dumper0 pid 14021
driver: started dumper1 pid 14022
driver: started dumper2 pid 14023
driver: started dumper3 pid 14024
planner: pid 14019 executable /usr/local/amanda/libexec/planner 
version 2.4.4p2
planner: build: VERSION="Amanda-2.4.4p2"
planner:        BUILT_DATE="Fri Jan 9 12:44:50 MET 2004"
planner:        BUILT_MACH="SunOS dev6 5.9 Generic_112233-08 sun4u 
sparc SUNW,Sun-Fire-V210"
planner:        CC="gcc"
planner:        CONFIGURE_COMMAND="'./configure' '--
prefix=/usr/local/amanda' '--with-user=amanda' '--with-group=backup'

.
.
.

GETTING ESTIMATES...
changer: got exit: 0 str: 7 11 1
changer: opening pipe to: /usr/local/amanda/libexec/chg-zd-mtx -slot 
current
changer: got exit: 0 str: 7 /dev/rmt/1cn
taper: slot 7: date X        label sun-edv-100 (new tape)
taper: read label `sun-edv-100' date `X'
taper: wrote label `sun-edv-100' date `20041009'
planner: time 16.902: got result for host A disk /dev/sda8: 0 -> 
326438K, -1 -> -1K, -1 -> -1K
planner: time 16.902: got result for host A disk /dev/sda6: 0 -> 
190574K, -1 -> -1K, -1 -> -1K
planner: time 16.903: got result for host A disk /dev/sda5: 0 -> 
1472150K, -1 -> -1K, -1 -> -1K
planner: time 114.488: got result for host B disk /dev/sda10: 0 -> 
3594281K, -1 -> -1K, -1 -> -1K
planner: time 114.488: got result for host B disk /dev/sda9: 0 -> 
79502196K, -1 -> -1K, -1 -> -1K
planner: time 114.488: got result for host B disk /dev/sda8: 0 -> 
28371084K, -1 -> -1K, -1 -> -1K
planner: time 114.488: got result for host B disk /dev/sda7: 0 -> 
1580753K, -1 -> -1K, -1 -> -1K
planner: time 114.488: got result for host B disk /dev/sda6: 0 -> 
3246229K, -1 -> -1K, -1 -> -1K
planner: time 114.489: got result for host B disk /dev/sda5: 0 -> 
345941K, -1 -> -1K, -1 -> -1K
planner: time 114.489: got result for host B disk /dev/sdb6: 0 -> 
2079464K, -1 -> -1K, -1 -> -1K
planner: time 114.500: getting estimates took 114.382 secs
FAILED QUEUE: empty
DONE QUEUE:
  0: A   /dev/sda8
  1: A   /dev/sda6
  2: A   /dev/sda5
  3: B    /dev/sda10
  4: B    /dev/sda9
  5: B    /dev/sda8
  6: B    /dev/sda7
  7: B    /dev/sda6
  8: B    /dev/sda5
  9: B    /dev/sdb6

ANALYZING ESTIMATES...
pondering A:/dev/sda8... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 163219 total size 163315 total_lev0 163219 
balanced-lev0size 32643
pondering A:/dev/sda6... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 95287 total size 258634 total_lev0 258506 
balanced-lev0size 51700
pondering A:/dev/sda5... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 736075 total size 994741 total_lev0 994581 
balanced-lev0size 198915
pondering B:/dev/sda10... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 1797140 total size 2791913 total_lev0 2791721 
balanced-lev0size 558343
pondering B:/dev/sda9... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 39751098 total size 42543043 total_lev0 42542819 
balanced-lev0size 8508562
pondering B:/dev/sda8... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 14185542 total size 56728617 total_lev0 56728361 
balanced-lev0size 11345670
pondering B:/dev/sda7... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 790376 total size 57519025 total_lev0 57518737 
balanced-lev0size 11503745
pondering B:/dev/sda6... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 1623114 total size 59142171 total_lev0 59141851 
balanced-lev0size 11828367
pondering B:/dev/sda5... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 172970 total size 59315173 total_lev0 59314821 
balanced-lev0size 11862961
pondering B:/dev/sdb6... next_level0 -12700 last_level -1 (due for 
level 0) (new disk, can't switch to degraded mode)
  curr level 0 size 1039732 total size 60354937 total_lev0 60354553 
balanced-lev0size 12070907
INITIAL SCHEDULE (size 60354937):
  B /dev/sda9 pri 12702 lev 0 size 39751098
  B /dev/sda8 pri 12702 lev 0 size 14185542
  B /dev/sda10 pri 12702 lev 0 size 1797140
  B /dev/sda6 pri 12702 lev 0 size 1623114
  B /dev/sdb6 pri 12702 lev 0 size 1039732
  B /dev/sda7 pri 12702 lev 0 size 790376
  A /dev/sda5 pri 12702 lev 0 size 736075
  B /dev/sda5 pri 12702 lev 0 size 172970
  A /dev/sda8 pri 12702 lev 0 size 163219
  A /dev/sda6 pri 12702 lev 0 size 95287

DELAYING DUMPS IF NEEDED, total_size 60354937, tape length 207618048 
mark 0
  delay: Total size now 60354937.

.
.
.

NO BAD LOOKING LINES!!

--- SNAP

... and some snippets out of the logfile "log":

--- SNIP

.
.
.

START taper datestamp 20041009 label sun-edv-100 tape 0
FINISH planner date 20041009
STATS driver startup time 114.590
SUCCESS dumper A /dev/sda6 20041009 0 [sec 38.571 kb 46087 kps 1194.8 
orig-kb 198700]
SUCCESS taper A /dev/sda6 20041009 0 [sec 7.086 kb 46088 kps 6503.8 
{wr: writers 1442 rdwait 0.000 wrwait 5.215 filemar
k 1.836}]
SUCCESS dumper B /dev/sda5 20041009 0 [sec 69.425 kb 142236 kps 
2048.8 orig-kb 355530]
SUCCESS taper B /dev/sda5 20041009 0 [sec 13.236 kb 142237 kps 
10746.0 {wr: writers 4446 rdwait 0.000 wrwait 11.402 file
mark 1.732}]
SUCCESS dumper A /dev/sda8 20041009 0 [sec 70.100 kb 43554 kps 621.3 
orig-kb 334290]
SUCCESS taper A /dev/sda8 20041009 0 [sec 6.830 kb 43555 kps 6377.0 
{wr: writers 1363 rdwait 0.000 wrwait 5.046 filemar
k 1.733}]
SUCCESS dumper A /dev/sda5 20041009 0 [sec 375.792 kb 515218 kps 
1371.0 orig-kb 1612230]
SUCCESS taper A /dev/sda5 20041009 0 [sec 38.929 kb 515219 kps 
13234.7 {wr: writers 16102 rdwait 0.011 wrwait 35.763 fi
lemark 2.518}]
SUCCESS dumper B /dev/sda7 20041009 0 [sec 500.951 kb 1553336 kps 
3100.8 orig-kb 1582370]
SUCCESS taper B /dev/sda7 20041009 0 [sec 108.685 kb 1553337 kps 
14292.1 {wr: writers 48543 rdwait 0.033 wrwait 104.828
filemark 1.734}]
SUCCESS dumper B /dev/sdb6 20041009 0 [sec 351.027 kb 662326 kps 
1886.8 orig-kb 2239370]
SUCCESS taper B /dev/sdb6 20041009 0 [sec 48.882 kb 662327 kps 
13549.3 {wr: writers 20699 rdwait 0.000 wrwait 45.982 fil
emark 1.714}]
SUCCESS dumper B /dev/sda6 20041009 0 [sec 932.264 kb 2958564 kps 
3173.5 orig-kb 3262990]
SUCCESS taper B /dev/sda6 20041009 0 [sec 208.219 kb 2958565 kps 
14208.9 {wr: writers 92457 rdwait 0.137 wrwait 201.611
filemark 2.117}]
FAIL dumper B /dev/sda10 20041009 0 [data timeout]
  sendbackup: start [B:/dev/sda10 level 0]
  sendbackup: info BACKUP=/sbin/dump
  sendbackup: info RECOVER_CMD=/sbin/restore -f... -
  sendbackup: info end
  |   DUMP: Date of this level 0 dump: Sat Oct  9 14:13:58 2004
  |   DUMP: Dumping /dev/sda10 (/mnt/netinst) to standard output
  |   DUMP: Added inode 8 to exclude list (journal inode)
  |   DUMP: Added inode 7 to exclude list (resize inode)
  |   DUMP: Label: /mnt/netinst
  |   DUMP: mapping (Pass I) [regular files]
  |   DUMP: mapping (Pass II) [directories]
  |   DUMP: estimated 3594281 tape blocks.
  |   DUMP: Volume 1 started with block 1 at: Sat Oct  9 14:14:23 2004
  |   DUMP: dumping (Pass III) [directories]
  |   DUMP: dumping (Pass IV) [regular files]
  |   DUMP: 42.92% done at 5142 kB/s, finished in 0:06
  | dump: ACLs in inode #3997763 won't be dumped: Invalid argument
  ?   DUMP: bread: lseek fails
... HUNDREDS OF THESE LINES ...
  ?   DUMP: bread: lseek fails
  |   DUMP: 100.00% done at 6154 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6474 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6598 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6682 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6774 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6843 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6888 kB/s, finished in 0:00
  |   DUMP: 100.00% done at 6929 kB/s, finished in 0:00
  | dump: ACLs in inode #4849729 won't be dumped: Invalid argument

--- SNAP

Server "A" and "B" are RedHat 9 Linux Machines running Amanda 2.4.4p3.

Anyone any ideas??? Thanks in advance.

Michael from Stuttgart

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