OK, we moved foward, only to trip over yet another issue. I got far enough
to try to run a backup set. Looks like that trigered a problem with taper.
amstutus is reporting, in addition to lot's of other normal stuff:
AW0400:/dev/dsk/c1t0d0s7 1 50m failed to tape: runtapes volumes
already written (9:41:57)
Here is the tape debug file:
1235053390.255627: taper: pid 12346 ruid 1001 euid 1001 version 2.6.1: start at
Thu Feb 19 09:23:10 2009
1235053390.260427: taper: taper: pid 12346 executable taper version 2.6.1
1235053390.260709: taper: "/opt/amanda/etc/amanda.conf.main", line 74: warning:
Keyword tapebufs is deprecated.
1235053390.260744: taper: "/opt/amanda/etc/amanda.conf.main", line 101:
warning: Keyword rawtapedev is deprecated.
1235053390.264348: taper: pid 12346 ruid 1001 euid 1001 version 2.6.1: rename
at Thu Feb 19 09:23:10 2009
1235053390.267546: taper: getcmd: START-TAPER 20090219092310
1235053390.271385: taper: changer: >> -info
"/opt/amanda/etc/amanda.conf.main", line 74: warning: Keyword tapebufs is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 101: warning: Keyword rawtapedev is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 74: warning: Keyword tapebufs is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 101: warning: Keyword rawtapedev is
deprecated.
1235053390.480263: taper: changer: << EXITSTATUS 0
1235053390.480318: taper: changer: << current 25 0 1
1235053390.480335: taper: changer_query: changer return was 25 0 1
1235053390.480363: taper: changer_query: searchable = 1
1235053390.480372: taper: changer_find: looking for DailyDump04 changer is
searchable = 1
1235053390.480383: taper: changer_search: DailyDump04
1235053390.480394: taper: changer: >> -search DailyDump04
"/opt/amanda/etc/amanda.conf.main", line 74: warning: Keyword tapebufs is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 101: warning: Keyword rawtapedev is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 74: warning: Keyword tapebufs is
deprecated.
"/opt/amanda/etc/amanda.conf.main", line 101: warning: Keyword rawtapedev is
deprecated.
1235053390.996550: taper: changer: << EXITSTATUS 0
1235053390.996596: taper: changer: << 5
rait:{file:/vtapes/DailyDump/vtape5,tape:/dev/nst0}
1235053391.144453: taper: changer_label: DailyDump04 for slot 5
1235053391.144470: taper: changer: >> -info
1235053391.144658: taper: changer: << EXITSTATUS 0
1235053391.144667: taper: changer: << current 25 0 1
1235053391.144675: taper: changer_query: changer return was 25 0 1
1235053391.144690: taper: changer_query: searchable = 1
1235053391.144697: taper: changer_label: calling changer -label DailyDump04
1235053391.144705: taper: changer: >> -label DailyDump04
1235053391.144803: taper: changer: << EXITSTATUS 0
1235053391.144812: taper: changer: << 5
rait:{file:/vtapes/DailyDump/vtape5,tape:/dev/nst0}
1235053391.144874: taper: putresult: 25 TAPER-OK
1235054382.752868: taper: getcmd: FILE-WRITE 40-00043
/dumpdisk/20090219092310/AW0400._dev_dsk_c1t0d0s7.1 AW0400 /dev/dsk/c1t0d0s7 1
20090219092310 0
1235054382.753211: taper: putresult: 26 REQUEST-NEW-TAPE
1235054382.754003: taper: getcmd: NEW-TAPE
1235054383.023385: taper: Building type 1 (TAPESTART) header of size 32768
using:
1235054383.023448: taper: Contents of *(dumpfile_t *)0x6501c0:
1235054383.023457: taper: type = 1 (TAPESTART)
1235054383.023465: taper: datestamp = '20090219092310'
1235054383.023472: taper: dumplevel = 0
1235054383.023479: taper: compressed = 0
1235054383.023487: taper: encrypted = 0
1235054383.023494: taper: comp_suffix = ''
1235054383.023501: taper: encrypt_suffix = ''
1235054383.023508: taper: name = 'DailyDump04'
1235054383.023516: taper: disk = ''
1235054383.023523: taper: program = ''
1235054383.023529: taper: application = ''
1235054383.023536: taper: srvcompprog = ''
1235054383.023543: taper: clntcompprog = ''
1235054383.023550: taper: srv_encrypt = ''
1235054383.023557: taper: clnt_encrypt = ''
1235054383.023564: taper: recover_cmd = ''
1235054383.023571: taper: uncompress_cmd = ''
1235054383.023578: taper: encrypt_cmd = ''
1235054383.023584: taper: decrypt_cmd = ''
1235054383.023591: taper: srv_decrypt_opt = ''
1235054383.023598: taper: clnt_decrypt_opt = ''
1235054383.023605: taper: cont_filename = ''
1235054383.023659: taper: dle_str = (null)
1235054383.023667: taper: is_partial = 0
1235054383.023675: taper: partnum = 0
1235054383.023682: taper: totalparts = 0
1235054383.023689: taper: blocksize = 32768
1235054383.413031: taper: Building type 1 (TAPESTART) header of size 32768
using:
1235054383.413154: taper: Contents of *(dumpfile_t *)0x64e160:
1235054383.413169: taper: type = 1 (TAPESTART)
1235054383.413178: taper: datestamp = '20090219092310'
1235054383.413185: taper: dumplevel = 0
1235054383.413223: taper: compressed = 0
1235054383.413230: taper: encrypted = 0
1235054383.413238: taper: comp_suffix = ''
1235054383.413266: taper: encrypt_suffix = ''
1235054383.413275: taper: name = 'DailyDump04'
1235054383.413287: taper: disk = ''
1235054383.413295: taper: program = ''
1235054383.413301: taper: application = ''
1235054383.413309: taper: srvcompprog = ''
1235054383.413320: taper: clntcompprog = ''
1235054383.413327: taper: srv_encrypt = ''
1235054383.413334: taper: clnt_encrypt = ''
1235054383.413409: taper: recover_cmd = ''
1235054383.413418: taper: uncompress_cmd = ''
1235054383.413425: taper: encrypt_cmd = ''
1235054383.413436: taper: decrypt_cmd = ''
1235054383.413444: taper: srv_decrypt_opt = ''
1235054383.413451: taper: clnt_decrypt_opt = ''
1235054383.413458: taper: cont_filename = ''
1235054383.413497: taper: dle_str = (null)
1235054383.413511: taper: is_partial = 0
1235054383.413518: taper: partnum = 0
1235054383.413525: taper: totalparts = 0
1235054383.413532: taper: blocksize = 32768
1235054394.542234: taper: changer_label: DailyDump04 for slot UNKNOWN
1235054394.542279: taper: changer: >> -info
1235054394.567000: taper: changer: << EXITSTATUS 0
1235054394.569613: taper: changer: << current 25 0 1
1235054394.569811: taper: changer_query: changer return was 25 0 1
1235054394.569901: taper: changer_query: searchable = 1
1235054394.569946: taper: changer_label: calling changer -label DailyDump04
1235054394.569971: taper: changer: >> -label DailyDump04
1235054394.571354: taper: changer: << EXITSTATUS 0
1235054394.571386: taper: changer: << 5
rait:{file:/vtapes/DailyDump/vtape5,tape:/dev/nst0}
1235054394.571556: taper: putresult: 18 NEW-TAPE
1235054398.871291: taper: putresult: 26 REQUEST-NEW-TAPE
1235054401.503960: taper: changer: >> -info
1235054407.366438: taper: getcmd: NO-NEW-TAPE "runtapes volumes already written"
1235054407.366558: taper: changer: << EXITSTATUS 0
1235054407.366572: taper: changer: << current 25 0 1
1235054407.366583: taper: changer_query: changer return was 25 0 1
1235054407.366688: taper: changer_query: searchable = 1
1235054407.366831: taper: changer_find: looking for DailyDump05 changer is
searchable = 1
1235054407.366845: taper: changer_search: DailyDump05
1235054414.346718: taper: changer: >> -search DailyDump05
1235054516.625178: taper: changer: << EXITSTATUS 1
1235054516.625240: taper: changer: << <error> Volume 'DailyDump05' not found
1235054516.625250: taper: changer: ERROR <error> Volume 'DailyDump05' not found
1235054516.625271: taper: changer: >> -slot current
1235054517.981742: taper: changer: << EXITSTATUS 0
1235054517.981819: taper: changer: << 4
rait:{file:/vtapes/DailyDump/vtape4,tape:/dev/nst0}
1235054517.982279: taper: putresult: 10 FAILED
Any thoughts as to what might be going on here?
--
One of the main causes of the fall of the roman empire was that, lacking
zero, they had no way to indicate successful termination of their C
programs.
