Scenario:
SME 6 running on HP 370 with 3x36 SCSI in hw Compaq RAID 5
- working tape backup to Sony AIT 74GB, restore capability tested OK
- swap out existing RAID array and install 3 spare disks build RAID5 OK
- boot from verified SME 7.0 iso and run install, OK
- do minimum configure
- run restore from tape
- restore runs for <1 min and then stops
Yes I have seen various other posts on this.
I tried this multiple times in each case starting from a clean wipe-and-install with the following variations:
- With/without yum software upgrade (which also seems to fail, not the problem just now)
- With/without running a backup before attempting a restore
I ran tail -F on messages from another console and saw various things pop up.
Extract from the log follows (*some* error lines bolded). I have seen error 1 in another post here going back to Feb06. Errors 2, 3, 4 do not occur on the 6 box using same hardware. Error 5 I find confusing.
Any suggestions for workrounds? Should I post this to the bug tracker?
TIA, Mej
Sep 30 12:30:45 mailserver1 esmith::event[4091]: Processing event: pre-restore
Sep 30 12:30:45 mailserver1 esmith::event[4091]: Running event handler: /etc/e-smith/events/pre-restore/S10save-system-files
Sep 30 12:30:45 mailserver1 esmith::event[4091]: S10save-system-files=action|Event|pre-restore|Action|S10save-system-files|Start|1159615845 608989|End|1159615845 686577|Elapsed|0.077588
Sep 30 12:30:45 mailserver1 esmith::event[4091]: Running event handler: /etc/e-smith/events/pre-restore/S25ldap-delete-dumps
Sep 30 12:30:45 mailserver1 slapd[3226]: slapd shutdown: waiting for 0 threads to terminate
Sep 30 12:30:45 mailserver1 slapd[3226]: slapd stopped.
Sep 30 12:30:45 mailserver1 slapcat: bdb_initialize: Sleepycat Software: Berkeley DB 4.2.52: (December 3, 2003)
Sep 30 12:30:45 mailserver1 slapcat: bdb_initialize: Sleepycat Software: Berkeley DB 4.2.52: (December 3, 2003)
Sep 30 12:30:45 mailserver1 slapcat: /etc/openldap/slapd.conf: line 18: schema checking disabled! your mileage may vary!
Sep 30 12:30:46 mailserver1 ldap: Stopping ldap succeeded
Sep 30 12:30:47 mailserver1 esmith::event[4091]: Stopping ldap:[ OK ]
Sep 30 12:30:47 mailserver1 esmith::event[4091]: S25ldap-delete-dumps=action|Event|pre-restore|Action|S25ldap-delete-dumps|Start|1159615845 687124|End|1159615846 952366|Elapsed|1.265242
Sep 30 12:30:47 mailserver1 esmith::event[4091]: Running event handler: /etc/e-smith/events/actions/adjust-services
Sep 30 12:30:47 mailserver1 esmith::event[4091]: adjusting supervised mysqld (stop)
Sep 30 12:30:47 mailserver1 esmith::event[4091]: adjust-services=action|Event|pre-restore|Action|adjust-services|Start|1159615846 952752|End|1159615847 151530|Elapsed|0.198778
Sep 30 12:30:47 mailserver1 esmith::event[4091]: Running event handler: /etc/e-smith/events/pre-restore/S99mysql-delete-db-files
Sep 30 12:30:47 mailserver1 esmith::event[4091]: S99mysql-delete-db-files=action|Event|pre-restore|Action|S99mysql-delete-db-files|Start|1159615847 152194|End|1159615847 173616|Elapsed|0.021422
Sep 30 12:30:47 mailserver1 esmith::event[4121]: Processing event: restore-tape
Sep 30 12:30:47 mailserver1 esmith::event[4121]: Running event handler: /etc/e-smith/events/restore-tape/S10tape-restore-flexbackup
[error#1]Sep 30 12:30:47 mailserver1 esmith::event[4121]: Name "cfg::set" used only once: possible typo at /etc/e-smith/events/restore-tape/S10tape-restore-flexbackup line 55.
Sep 30 12:30:47 mailserver1 kernel: st0: Block limits 2 - 16777215 bytes.
[error #2]Sep 30 12:30:58 mailserver1 kernel: st0: Incorrect block size.[error #3]Sep 30 12:31:04 mailserver1 esmith::event[4121]: dd: reading `/dev/nst0': Input/output errorSep 30 12:31:04 mailserver1 esmith::event[4121]: 0+0 records in
Sep 30 12:31:04 mailserver1 esmith::event[4121]: 0+0 records out
Sep 30 12:31:16 mailserver1 esmith::event[4121]:
Sep 30 12:31:16 mailserver1 esmith::event[4121]: flexbackup version 1.2.1 (
http://flexbackup.sourceforge.net)
Sep 30 12:31:16 mailserver1 esmith::event[4121]: /etc/flexbackup.conf syntax OK
Sep 30 12:31:16 mailserver1 esmith::event[4121]: (override) blksize = 0
Sep 30 12:31:16 mailserver1 esmith::event[4121]:
[error #4]Sep 30 12:31:16 mailserver1 esmith::event[4121]: $keyfile not found in config: default=00-index-key
Sep 30 12:31:16 mailserver1 esmith::event[4121]: $buffer_fill_pct not found in config: default=75
Sep 30 12:31:16 mailserver1 esmith::event[4121]: $buffer_pause_usec not found in config: default=100Sep 30 12:31:16 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | Checking 'buffer' on this machine... Ok
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | Checking /bin/sh on this machine... bash1
Sep 30 12:31:16 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | Logging output to "flexbackup.extract.200609301231.log"
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | Reading from CURRENT TAPE POSITION
Sep 30 12:31:16 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:16 mailserver1 esmith::event[4121]: SCSI 2 tape drive:
Sep 30 12:31:16 mailserver1 esmith::event[4121]: File number=1, block number=0, partition=0.
Sep 30 12:31:16 mailserver1 esmith::event[4121]: Tape block size 0 bytes. Density code 0x3a (no translation).
Sep 30 12:31:16 mailserver1 esmith::event[4121]: Soft error count since last status=0
Sep 30 12:31:16 mailserver1 esmith::event[4121]: General status bits on (81010000):
Sep 30 12:31:16 mailserver1 esmith::event[4121]: EOF ONLINE IM_REP_EN
Sep 30 12:31:16 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | Extracting files listed in /tmp/restore.4122
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | buffer -m 20m -p 75 -t -u 100 -i "/dev/nst0" | gzip -dq | tar --extract \
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | --files-from /tmp/extract.4134 --totals --same-permissions --sparse \
Sep 30 12:31:16 mailserver1 esmith::event[4121]: | -B --file -
Sep 30 12:31:16 mailserver1 esmith::event[4121]: |------------------------------------------------------------
[error #5]Sep 30 12:31:19 mailserver1 esmith::event[4121]:
Sep 30 12:31:19 mailserver1 esmith::event[4121]: gzip: stdin: not in gzip format
Sep 30 12:31:19 mailserver1 esmith::event[4121]: buffer (writer): write of data failed: SuccessSep 30 12:31:19 mailserver1 esmith::event[4121]: bytes to write=10240, bytes written=4096, total written 30K
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./home/e-smith: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/e-smith/templates-custom: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/e-smith/templates-user-custom: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/ssh: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./root: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/sudoers: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/passwd: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/shadow: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/group: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/gshadow: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/samba/secrets.tdb: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/samba/smbpasswd: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: ./etc/smbpasswd: Not found in archive
Sep 30 12:31:19 mailserver1 esmith::event[4121]: tar: Error exit delayed from previous errors
Sep 30 12:31:19 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:19 mailserver1 esmith::event[4121]: SCSI 2 tape drive:
Sep 30 12:31:19 mailserver1 esmith::event[4121]: File number=1, block number=1543, partition=0.
Sep 30 12:31:19 mailserver1 esmith::event[4121]: Tape block size 0 bytes. Density code 0x3a (no translation).
Sep 30 12:31:19 mailserver1 esmith::event[4121]: Soft error count since last status=0
Sep 30 12:31:19 mailserver1 esmith::event[4121]: General status bits on (1010000):
Sep 30 12:31:19 mailserver1 esmith::event[4121]: ONLINE IM_REP_EN
Sep 30 12:31:19 mailserver1 esmith::event[4121]: |------------------------------------------------------------
Sep 30 12:31:19 mailserver1 esmith::event[4121]: S10tape-restore-flexbackup=action|Event|restore-tape|Action|S10tape-restore-flexbackup|Start|1159615847 365778|End|1159615879 659682|Elapsed|32.293904
Sep 30 12:31:19 mailserver1 esmith::event[4121]: Running event handler: /etc/e-smith/events/restore-tape/S40restore-from-disk
Sep 30 12:31:19 mailserver1 esmith::event[4121]: S40restore-from-disk=action|Event|restore-tape|Action|S40restore-from-disk|Start|1159615879 660134|End|1159615879 844338|Elapsed|0.184204
Sep 30 12:31:19 mailserver1 esmith::event[4121]: Running event handler: /etc/e-smith/events/restore-tape/S50rewind-tape
Sep 30 12:31:19 mailserver1 esmith::event[4121]: S50rewind-tape=action|Event|restore-tape|Action|S50rewind-tape|Start|1159615879 844894|End|1159615879 981931|Elapsed|0.137037
Sep 30 12:31:20 mailserver1 esmith::event[4172]: Processing event: post-upgrade
Sep 30 12:31:20 mailserver1 esmith::event[4172]: Running event handler: /etc/e-smith/events/post-upgrade/S00initialize-default-databases
Sep 30 12:31:20 mailserver1 esmith::event[4172]: Migrating existing database domains
Sep 30 12:31:20 mailserver1 esmith::event[4172]: Migrating existing database yum_installed
Sep 30 12:31:20 mailserver1 esmith::event[4172]: Migrating existing database configuration
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database backups
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database hosts
Sep 30 12:31:21 mailserver1 /etc/e-smith/events/post-upgrade/S00initialize-default-databases[4173]: /home/e-smith/db/hosts: OLD mailserver1.mycompany.local=host|ExternalIP||HostType|Self|InternalIP||MACAddress||static|yes
Sep 30 12:31:21 mailserver1 /etc/e-smith/events/post-upgrade/S00initialize-default-databases[4173]: /home/e-smith/db/hosts: NEW mailserver1.mycompany.local=host|ExternalIP||HostType|Self|InternalIP||MACAddress||ReverseDNS|yes|static|yes
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database yum_available
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database mailpatterns
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database accounts
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database spamassassin
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database networks
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database yum_updates
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Migrating existing database yum_repositories
Sep 30 12:31:21 mailserver1 esmith::event[4172]: S00initialize-default-databases=action|Event|post-upgrade|Action|S00initialize-default-databases|Start|1159615880 344585|End|1159615881 842836|Elapsed|1.498251
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Running event handler: /etc/e-smith/events/post-upgrade/S00merge-system-files
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/sudoers as etc/sudoers.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/passwd as etc/passwd.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/shadow as etc/shadow.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/group as etc/group.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/gshadow as etc/gshadow.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/samba/secrets.tdb as etc/samba/secrets.tdb.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Preserving etc/samba/smbpasswd as etc/samba/smbpasswd.1159615881
Sep 30 12:31:21 mailserver1 esmith::event[4172]: S00merge-system-files=action|Event|post-upgrade|Action|S00merge-system-files|Start|1159615881 843315|End|1159615881 937940|Elapsed|0.094625
Sep 30 12:31:21 mailserver1 esmith::event[4172]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /home/httpd/html/horde/scripts/db/mysql_set_password.sql
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/fstab
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/syslog.conf
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/redhat-release
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/crontab
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/mime.types
Sep 30 12:31:22 mailserver1 esmith::event[4172]: expanding /etc/issue.net
0 Replies
1552 Views