Topic: Server-manager crash on dual CPU system during tape backup

[Beast]

Hi All

I have some new (old) hardware that I like to use for my private server. Currently I am testing to verify that it will work without errors.

It is a HP dual CPU PIII 500 MHz E60 server and 512 Mb ram with a HP SureStore T20 tape drive (Travan). Using SME 6.0 with software raid (all 9 GB SCSI drives, but also a IDE drive (not mounted) is installed)

If I do a fresh install and then configure a tape backup at default 2 AM, the server-manager hang in the morning. Also the attached CRT screen is black. Some things are working like the default web page, but all the administration is not working.

Looks like is is related to the dual processor system. When I boot up the system without support for dual processor, I see no problems.

I have tested the hardware with the official DiagTool for HP with no errors detected.

It may be a bug in the SME software?

Beast

NB: The backup job seam to be OK, because I am able to do a restore.

PS: Messages log file.

Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: OLD 1077325201=(undefined)
Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: NEW 1077325201=backup_record
Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: OLD 1077325201=backup_record
Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: NEW 1077325201=backup_record|StartEpochTime|1077325201
Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: OLD 1077325201=backup_record|StartEpochTime|1077325201
Feb 21 02:00:01 hpe60 /sbin/e-smith/do_backup[2098]: /home/e-smith/backups: NEW 1077325201=backup_record|BackupType|tape|StartEpochTime|1077325201
Feb 21 02:00:01 hpe60 e-smith[2100]: Processing event: pre-backup
Feb 21 02:00:01 hpe60 e-smith[2100]: Running event handler: /etc/e-smith/events/pre-backup/S10mysql-delete-dumped-tables
Feb 21 02:00:01 hpe60 e-smith[2100]: S10mysql-delete-dumped-tables=action|Event|pre-backup|Action|S10mysql-delete-dumped-tables|Start|1077325201 462613|End|1077325201 630909|Elapsed|0.168296
Feb 21 02:00:01 hpe60 e-smith[2100]: Running event handler: /etc/e-smith/events/pre-backup/S20mysql-dump-tables
Feb 21 02:00:02 hpe60 e-smith[2100]: S20mysql-dump-tables=action|Event|pre-backup|Action|S20mysql-dump-tables|Start|1077325201 631297|End|1077325202 392187|Elapsed|0.76089
Feb 21 02:00:02 hpe60 e-smith[2100]: Running event handler: /etc/e-smith/events/pre-backup/S25gentle-ldap-dump
Feb 21 02:00:03 hpe60 e-smith[2100]: S25gentle-ldap-dump=action|Event|pre-backup|Action|S25gentle-ldap-dump|Start|1077325202 392542|End|1077325203 116147|Elapsed|0.723605
Feb 21 02:00:03 hpe60 e-smith[2100]: Running event handler: /etc/e-smith/events/pre-backup/S50rewind-tape
Feb 21 02:00:03 hpe60 kernel: st0: Block limits 512 - 512 bytes.
Feb 21 02:00:03 hpe60 e-smith[2100]: S50rewind-tape=action|Event|pre-backup|Action|S50rewind-tape|Start|1077325203 116501|End|1077325203 538651|Elapsed|0.42215
Feb 21 02:00:04 hpe60 kernel: st0: Error with sense data: Current st09:00: sense key Illegal Request
Feb 21 02:00:04 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 02:00:04 hpe60 kernel: st0: Error with sense data: Current st09:00: sense key Illegal Request
Feb 21 02:00:04 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 02:00:04 hpe60 kernel: st0: Default block size set to 0 bytes.
Feb 21 02:00:04 hpe60 kernel: st0: Error with sense data: Current st09:00: sense key Illegal Request
Feb 21 02:00:04 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 02:00:04 hpe60 kernel: st0: Can't set default block size to 0 bytes and density ffffffff.
Feb 21 02:00:04 hpe60 kernel: st0: Default block size set to 0 bytes.
Feb 21 02:00:04 hpe60 kernel: st0: Error with sense data: Current st09:00: sense key Illegal Request
Feb 21 02:00:04 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 02:00:04 hpe60 kernel: st0: Can't set default block size to 0 bytes and density ffffffff.
Feb 21 02:17:17 hpe60 kernel: scsi0:0:4:0: Attempting to queue an ABORT message
Feb 21 02:17:17 hpe60 kernel: scsi0: Dumping Card State while idle, at SEQADDR 0x7
Feb 21 02:17:17 hpe60 kernel: ACCUM = 0xe2, SINDEX = 0x17, DINDEX = 0xe4, ARG_2 = 0x0
Feb 21 02:17:17 hpe60 kernel: HCNT = 0x0 SCBPTR = 0x4
Feb 21 02:17:17 hpe60 kernel: SCSISEQ = 0x12, SBLKCTL = 0x2
Feb 21 02:17:17 hpe60 kernel: DFCNTRL = 0x0, DFSTATUS = 0x2d
Feb 21 02:17:17 hpe60 kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80
Feb 21 02:17:17 hpe60 kernel: SSTAT0 = 0x5, SSTAT1 = 0xa
Feb 21 02:17:17 hpe60 kernel: STACK == 0x3, 0xf7, 0x150, 0x0
Feb 21 02:17:17 hpe60 kernel: SCB count = 76
Feb 21 02:17:17 hpe60 kernel: Kernel NEXTQSCB = 44
Feb 21 02:17:17 hpe60 kernel: Card NEXTQSCB = 44
Feb 21 02:17:17 hpe60 kernel: QINFIFO entries:
Feb 21 03:31:45 hpe60 kernel: Waiting Queue entries:
Feb 21 03:31:45 hpe60 kernel: Disconnected Queue entries: 2:31
Feb 21 03:31:45 hpe60 kernel: QOUTFIFO entries:
Feb 21 03:31:45 hpe60 kernel: Sequencer Free SCB List: 4 16 1 8 9 29 22 15 19 7 26 13 23 25 3 11 6 17 21 28 31 14 30 18 12 20 5 10 27 24 0
Feb 21 03:31:45 hpe60 kernel: Sequencer SCB Info: 0(c 0x68, s 0x7, l 0, t 0xff) 1(c 0x68, s 0x7, l 0, t 0xff) 2(c 0x44, s 0x47, l 0, t 0x1f) 3(c 0x68, s 0x7, l 0, t 0xff) 4(c 0x68, s 0x7, l 0, t 0xff) 5(c 0x68, s 0x7, l 0, t 0xff) 6(c 0x68, s 0x7, l 0, t 0xff) 7(c 0x68, s 0x7, l 0, t 0xff) 8(c 0x68, s 0x7, l 0, t 0xff) 9(c 0x68, s 0x7, l 0, t 0xff) 10(c 0x68, s 0x7, l 0, t 0xff) 11(c 0x68, s 0x7, l 0, t 0xff) 12(c 0x68, s 0x7, l 0, t 0xff) 13(c 0x68, s 0x7, l 0, t 0xff) 14(c 0x68, s 0x7, l 0, t 0xff) 15(c 0x68, s 0x7, l 0, t 0xff) 16(c 0x68, s 0x7, l 0, t 0xff) 17(c 0x68, s 0x7, l 0, t 0xff) 18(c 0x68, s 0x7, l 0, t 0xff) 19(c 0x68, s 0x7, l 0, t 0xff) 20(c 0x68, s 0x7, l 0, t 0xff) 21(c 0x68, s 0x7, l 0, t 0xff) 22(c 0x68, s 0x7, l 0, t 0xff) 23(c 0x68, s 0x7, l 0, t 0xff) 24(c 0x68, s 0x7, l 0, t 0xff) 25(c 0x68, s 0x7, l 0, t 0xff) 26(c 0x68, s 0x7, l 0, t 0xff) 27(c 0x68, s 0x7, l 0, t 0xff) 28(c 0x68, s 0x7, l 0, t 0xff) 29(c 0x68, s 0x7, l 0, t 0xff) 30(c 0x68, s 0x7, l 0, t 0xff) 31(c 0x68, s 0x7, l 0,
Feb 21 03:31:45 hpe60 kernel: 0xff)
Feb 21 03:31:45 hpe60 kernel: Pending list: 31(c 0x40, s 0x47, l 0)
Feb 21 03:31:45 hpe60 kernel: Kernel Free SCB list: 23 53 41 17 72 7 43 32 55 20 0 49 12 24 62 39 60 29 45 4 22 58 73 13 46 36 5 21 27 11 33 48 52 2 56 51 34 15 8 47 3 54 42 38 57 35 63 26 16 50 59 28 30 25 18 6 40 37 1 10 14 9 19 61 67 74 75 68 69 70 71 64 65 66
Feb 21 03:31:45 hpe60 kernel: Untagged Q(4): 31
Feb 21 03:31:45 hpe60 kernel: DevQ(0:0:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: DevQ(0:1:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: DevQ(0:4:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: (scsi0:A:4:0): Queuing a recovery SCB
Feb 21 03:31:45 hpe60 kernel: scsi0:0:4:0: Device is disconnected, re-queuing SCB
Feb 21 03:31:45 hpe60 kernel: Recovery code sleeping
Feb 21 03:31:45 hpe60 kernel: (scsi0:A:4:0): Abort Message Sent
Feb 21 03:31:45 hpe60 kernel: (scsi0:A:4:0): SCB 31 - Abort Completed.
Feb 21 03:31:45 hpe60 kernel: Recovery SCB completes
Feb 21 03:31:45 hpe60 kernel: Recovery code awake
Feb 21 03:31:45 hpe60 kernel: aic7xxx_abort returns 0x2002
Feb 21 03:31:45 hpe60 kernel: scsi0:0:4:0: Attempting to queue an ABORT message
Feb 21 03:31:45 hpe60 kernel: scsi0: Dumping Card State in Command phase, at SEQADDR 0x158
Feb 21 03:31:45 hpe60 kernel: ACCUM = 0x80, SINDEX = 0xa0, DINDEX = 0xe4, ARG_2 = 0xb
Feb 21 03:31:45 hpe60 kernel: HCNT = 0x0 SCBPTR = 0x2
Feb 21 03:31:45 hpe60 kernel: SCSISEQ = 0x12, SBLKCTL = 0x2
Feb 21 03:31:45 hpe60 kernel: DFCNTRL = 0x4, DFSTATUS = 0x6d
Feb 21 03:31:45 hpe60 kernel: LASTPHASE = 0x80, SCSISIGI = 0x84, SXFRCTL0 = 0x88
Feb 21 03:31:45 hpe60 kernel: SSTAT0 = 0x7, SSTAT1 = 0x2
Feb 21 03:31:45 hpe60 kernel: STACK == 0x166, 0x0, 0xd6, 0x37
Feb 21 03:31:45 hpe60 kernel: SCB count = 76
Feb 21 03:31:45 hpe60 kernel: Kernel NEXTQSCB = 31
Feb 21 03:31:45 hpe60 kernel: Card NEXTQSCB = 31
Feb 21 03:31:45 hpe60 kernel: QINFIFO entries:
Feb 21 03:31:45 hpe60 kernel: Waiting Queue entries:
Feb 21 03:31:45 hpe60 kernel: Disconnected Queue entries:
Feb 21 03:31:45 hpe60 kernel: QOUTFIFO entries:
Feb 21 03:31:45 hpe60 kernel: Sequencer Free SCB List: 4 16 1 8 9 29 22 15 19 7 26 13 23 25 3 11 6 17 21 28 31 14 30 18 12 20 5 10 27 24 0
Feb 21 03:31:45 hpe60 kernel: Sequencer SCB Info: 0(c 0x68, s 0x7, l 0, t 0xff) 1(c 0x68, s 0x7, l 0, t 0xff) 2(c 0x40, s 0x47, l 0, t 0x2c) 3(c 0x68, s 0x7, l 0, t 0xff) 4(c 0x68, s 0x7, l 0, t 0xff) 5(c 0x68, s 0x7, l 0, t 0xff) 6(c 0x68, s 0x7, l 0, t 0xff) 7(c 0x68, s 0x7, l 0, t 0xff) 8(c 0x68, s 0x7, l 0, t 0xff) 9(c 0x68, s 0x7, l 0, t 0xff) 10(c 0x68, s 0x7, l 0, t 0xff) 11(c 0x68, s 0x7, l 0, t 0xff) 12(c 0x68, s 0x7, l 0, t 0xff) 13(c 0x68, s 0x7, l 0, t 0xff) 14(c 0x68, s 0x7, l 0, t 0xff) 15(c 0x68, s 0x7, l 0, t 0xff) 16(c 0x68, s 0x7, l 0, t 0xff) 17(c 0x68, s 0x7, l 0, t 0xff) 18(c 0x68, s 0x7, l 0, t 0xff) 19(c 0x68, s 0x7, l 0, t 0xff) 20(c 0x68, s 0x7, l 0, t 0xff) 21(c 0x68, s 0x7, l 0, t 0xff) 22(c 0x68, s 0x7, l 0, t 0xff) 23(c 0x68, s 0x7, l 0, t 0xff) 24(c 0x68, s 0x7, l 0, t 0xff) 25(c 0x68, s 0x7, l 0, t 0xff) 26(c 0x68, s 0x7, l 0, t 0xff) 27(c 0x68, s 0x7, l 0, t 0xff) 28(c 0x68, s 0x7, l 0, t 0xff) 29(c 0x68, s 0x7, l 0, t 0xff) 30(c 0x68, s 0x7, l 0, t 0xff) 31(c 0x68, s 0x7, l 0,
Feb 21 03:31:45 hpe60 kernel: 0xff)
Feb 21 03:31:45 hpe60 kernel: Pending list: 44(c 0x40, s 0x47, l 0)
Feb 21 03:31:45 hpe60 kernel: Kernel Free SCB list: 23 53 41 17 72 7 43 32 55 20 0 49 12 24 62 39 60 29 45 4 22 58 73 13 46 36 5 21 27 11 33 48 52 2 56 51 34 15 8 47 3 54 42 38 57 35 63 26 16 50 59 28 30 25 18 6 40 37 1 10 14 9 19 61 67 74 75 68 69 70 71 64 65 66
Feb 21 03:31:45 hpe60 kernel: Untagged Q(4): 44
Feb 21 03:31:45 hpe60 kernel: DevQ(0:0:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: DevQ(0:1:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: DevQ(0:4:0): 0 waiting
Feb 21 03:31:45 hpe60 kernel: scsi0:0:4:0: Device is active, asserting ATN
Feb 21 03:31:45 hpe60 kernel: Recovery code sleeping
Feb 21 03:31:45 hpe60 kernel: Recovery code awake
Feb 21 03:31:45 hpe60 kernel: Timer Expired
Feb 21 03:31:45 hpe60 kernel: aic7xxx_abort returns 0x2003
Feb 21 03:31:46 hpe60 kernel: scsi0:0:4:0: Attempting to queue a TARGET RESET message
Feb 21 03:31:46 hpe60 kernel: aic7xxx_dev_reset returns 0x2003
Feb 21 03:31:46 hpe60 kernel: Recovery SCB completes
Feb 21 03:31:46 hpe60 kernel: st0: Stepping over filemark backward failed.
Feb 21 03:31:46 hpe60 kernel: st0: Error with sense data: Current st09:00: sense key Illegal Request
Feb 21 03:31:46 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 03:31:46 hpe60 kernel: st0: Can't set default block size to 0 bytes and density ffffffff.
Feb 21 03:32:30 hpe60 kernel: st0: Error with sense data: Info fld=0x2a00, Deferred st09:00: sense key Medium Error
Feb 21 03:32:30 hpe60 kernel: Additional sense indicates Positioning error detected by read of medium
Feb 21 03:32:30 hpe60 kernel: st0: Error on write filemark.
Feb 21 03:32:34 hpe60 kernel: st0: Error with sense data: Deferred st09:00: sense key Illegal Request
Feb 21 03:32:34 hpe60 kernel: Additional sense indicates Invalid field in parameter list
Feb 21 03:32:34 hpe60 kernel: st0: Can't set default block size to 0 bytes and density ffffffff.

[compsos]

Hi

I have just got the same result as Beast. That is restore after upgarde failing due to block size of 0 and sense error. This has occurred on Seagate IDE Travan drive 4/8Gb. The backup log is fine but restore crashes the NOS.

Upgrade 5.5-->6.0-->6.0.1

Has anyone else seen this problem or know of a work around.

[compsos]

Forgot to mention that
flexbackup -test-tape-drive passes with no errors

[wizard]

this happens to me as well... if i ssh into the box i noticed i can actually do a commmand line backup but if left to the server it fails
...

i run dual 550Mhz with mirrored 9.1gb scsi drives and scsi cdrom and scsi travan 4/8gb drive.....

running latest sme server from contribs ...

also couldnt backup to desktop.... rebooted now backs up to desktop... trying auto backup with flex....

[guest22]

Ok.... time to enter this one in the Bug Tracker I guess.

Beast, would you do the honors please?

TIA
RequestedDeletion