Hi All,
I have these settings in amanda.conf:
taper-parallel-write 2
taperalgo firstfit
flush-threshold-dumped 100
flush-threshold-scheduled 100
taperflush 0
autoflush yes
define device top_drive {
tapedev "tape:/dev/nst0"
device_property "LEOM" "TRUE"
}
define device bottom_drive {
tapedev "tape:/dev/nst1"
device_property "LEOM" "TRUE"
}
tpchanger "chg-multi:{top_drive,bottom_drive}"
changerfile "/etc/amanda/chg-multi-state"
After upgrading to from FreeBSD/3.2.3 to CentOS/3.3.0 I noticed these
changes:
- with 3.2.3 amanda first dumped then flushed the gathered data to 2
tape drives in parallel
- with 3.3.0
amanda gathered an LTO-4 tapesize data (750G) and started to flush using
1 drive while continuing dumping the remaining data
the last DLE didn't fit the top drive tape, amanda switched to bottom
drive and flushed the remaining 4 partitions there.
Then it said "No Acceptable volumes found" and left the remaining DLEs
on the holding disk, which utilizing the second Daily-19 tape in the
bottom_drive.
The strange thing is that it used only 130G of second tape and left 570G
on the holding disk, which it could have flushed to the second tape.
I have very verbose taper log.
amanda$ grep -e TAPE -e Daily -e worker1 taper.20111008034953.debug
Sat Oct 8 03:49:53 2011: taper: driver/taper << START-TAPER worker0
20111008034952
Sat Oct 8 03:49:53 2011: taper: Amanda::Taper::Scan::traditional oldest
reusable volume is 'Daily-18'
Sat Oct 8 03:49:53 2011: taper: Amanda::Taper::Scan::traditional
result: 'Daily-18' on top_drive slot 1, mode 2
Sat Oct 8 03:49:53 2011: taper: driver/taper >> TAPER-OK worker0
Sat Oct 8 13:46:25 2011: taper: driver/taper << FILE-WRITE worker0
01-00116 /scratch/Daily/20111008034952/xena.linguamatics.com._boot.1
xena.linguamatics.com /boot 1 20111008034952 "" "" "" "" 42949672960 ""
"" "" 10
Sat Oct 8 13:46:26 2011: taper: driver/taper >> REQUEST-NEW-TAPE 01-00116
Sat Oct 8 13:46:26 2011: taper: driver/taper << NEW-TAPE worker0 01-00116
Sat Oct 8 13:46:26 2011: taper: Building type TAPESTART header of
65536-65536 bytes with name='Daily-18' disk='' dumplevel=0 and
blocksize=65536
Sat Oct 8 13:46:36 2011: taper: driver/taper >> NEW-TAPE 01-00116 Daily-18
Sat Oct 8 13:46:36 2011: taper: driver/taper << START-TAPER worker1
20111008034952
Sat Oct 8 13:46:36 2011: taper: Amanda::Taper::Scan::traditional oldest
reusable volume is 'Daily-19'
Sat Oct 8 13:46:36 2011: taper: Amanda::Taper::Scan::traditional
result: 'Daily-19' on bottom_drive slot 2, mode 2
Sat Oct 8 13:46:36 2011: taper: driver/taper >> TAPER-OK worker1
Sat Oct 8 13:46:41 2011: taper: driver/taper >> PARTDONE 01-00116
Daily-18 1 0 "[sec 5.070624 bytes 806 kps 0.000000 orig-kb 10]"
Sat Oct 8 13:46:41 2011: taper: driver/taper >> DONE 01-00116
INPUT-GOOD TAPE-GOOD "[sec 15.000000 bytes 806 kps 0.000000 orig-kb 10]"
"" ""
[ continues writing parts to top_drive (Daily-18) ]
Sat Oct 8 16:53:17 2011: taper: driver/taper >> DONE 01-00227
INPUT-GOOD TAPE-GOOD "[sec 3131.000000 bytes 246340008191 kps
76833.731715 orig-kb 585792060]" "" ""
Sat Oct 8 19:31:10 2011: taper: driver/taper << FILE-WRITE worker0
02-00229
/scratch/Daily/20111008034952/files.linguamatics.com._export_proj_ieir.1
files.linguamatics.com /export/proj/ieir 1 20111008034952 "" "" "" ""
42949672960 "" "" "" 194542840
Sat Oct 8 19:32:01 2011: taper: driver/taper >> PARTDONE 02-00229
Daily-18 125 3714112 "[sec 50.270145 bytes 3803250688 kps 73883.056823
orig-kb 194542840]"
Sat Oct 8 19:32:15 2011: taper: driver/taper >> REQUEST-NEW-TAPE 02-00229
Sat Oct 8 19:32:15 2011: taper: driver/taper << TAKE-SCRIBE-FROM
worker0 02-00229 worker1
Sat Oct 8 19:32:15 2011: taper: driver/taper >> REQUEST-NEW-TAPE 02-00229
Sun Oct 9 00:24:11 2011: taper: driver/taper << NEW-TAPE worker0 02-00229
Sun Oct 9 00:24:11 2011: taper: Building type TAPESTART header of
65536-65536 bytes with name='Daily-19' disk='' dumplevel=0 and
blocksize=65536
Sun Oct 9 00:24:22 2011: taper: driver/taper >> NEW-TAPE 02-00229 Daily-19
Sun Oct 9 00:24:22 2011: taper: driver/taper << START-TAPER worker1
20111008034952
Sun Oct 9 00:24:22 2011: taper: Amanda::Taper::Scan::traditional oldest
reusable volume is 'Daily-20'
Sun Oct 9 00:24:22 2011: taper: driver/taper >> TAPE-ERROR worker1 "No
acceptable volumes found"
Sun Oct 9 00:33:30 2011: taper: driver/taper >> PARTDONE 02-00229
Daily-19 1 41943040 "[sec 548.550823 bytes 42949672960 kps 76461.538704
orig-kb 194542840]"
Sun Oct 9 00:42:40 2011: taper: driver/taper >> PARTDONE 02-00229
Daily-19 2 41943040 "[sec 549.363641 bytes 42949672960 kps 76348.409083
orig-kb 194542840]"
Sun Oct 9 00:51:45 2011: taper: driver/taper >> PARTDONE 02-00229
Daily-19 3 41943040 "[sec 545.108282 bytes 42949672960 kps 76944.418970
orig-kb 194542840]"
Sun Oct 9 00:51:59 2011: taper: driver/taper >> PARTDONE 02-00229
Daily-19 4 1056044 "[sec 14.526950 bytes 1081389603 kps 72695.505870
orig-kb 194542840]"
Sun Oct 9 00:51:59 2011: taper: driver/taper >> DONE 02-00229
INPUT-GOOD TAPE-GOOD "[sec 19249.000000 bytes 133733659171 kps
6784.730428 orig-kb 194542840]" "" ""
Note the "No Acceptable volumes found" message - despite the fact that
Daily-19 was in the second drive.
Is this a bug or some configuration semantics have changed 3.2->3.3?
Thanks,
Attila
