[Linux-PowerEdge] T20 + H310 + Proxmox boot problem

Eneko Lacunza elacunza at binovo.es
Thu Oct 2 10:40:44 CDT 2014


Hi all,

We have a T20 with Proxmox. It boots fine using onboard SATA and 2x4TB 
disks, grub2, kernels 2.6.32 and 3.10.0 from Proxmox (Debian based 
distro, RHEL kernels).

But, if I add a H310 card to the T20, system won't boot and lots of 
"udevd timeout: killing /sbin/modprobe ..." messages will appear.

After about 7m30s it shows an initramfs CLI. There I can run "mdadm 
--assemble -s" and "vgchange -a y", then exit and booting resumes normally.

So the problem seem to be with H310 detection, that delays onboard SATA 
detection and root filesystem on md/lvm isn't ready on time. T310 only 
has one SSD from Crucial, onboard SATA has 2x4TB WD + 2x1TB Seagate.

Syslog shows the following messages (removed e1000e+usb logs):
Oct  2 16:36:37 txoritokieta kernel: [    0.568419] scsi0 : LSI SAS 
based MegaRAID driver
Oct  2 16:36:37 txoritokieta kernel: [    0.628815] ahci 0000:00:1f.2: 
version 3.0
Oct  2 16:36:37 txoritokieta kernel: [    0.628931] ahci 0000:00:1f.2: 
irq 47 for MSI/MSI-X
Oct  2 16:36:37 txoritokieta kernel: [    0.628996] ahci 0000:00:1f.2: 
AHCI 0001.0300 32 slots 6 ports 6 Gbps 0xf impl RAID mode
Oct  2 16:36:37 txoritokieta kernel: [    0.628998] ahci 0000:00:1f.2: 
flags: 64bit ncq pm led clo pio slum part ems apst
Oct  2 16:36:37 txoritokieta kernel: [    0.634619] scsi1 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634667] scsi2 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634714] scsi3 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634758] scsi4 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634806] scsi5 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634847] scsi6 : ahci
Oct  2 16:36:37 txoritokieta kernel: [    0.634909] ata1: SATA max 
UDMA/133 abar m2048 at 0xf7e36000 port 0xf7e36100 irq 47
Oct  2 16:36:37 txoritokieta kernel: [    0.634911] ata2: SATA max 
UDMA/133 abar m2048 at 0xf7e36000 port 0xf7e36180 irq 47
Oct  2 16:36:37 txoritokieta kernel: [    0.634913] ata3: SATA max 
UDMA/133 abar m2048 at 0xf7e36000 port 0xf7e36200 irq 47
Oct  2 16:36:37 txoritokieta kernel: [    0.634915] ata4: SATA max 
UDMA/133 abar m2048 at 0xf7e36000 port 0xf7e36280 irq 47
Oct  2 16:36:37 txoritokieta kernel: [    0.634916] ata5: DUMMY
Oct  2 16:36:37 txoritokieta kernel: [    0.634917] ata6: DUMMY
Oct  2 16:36:37 txoritokieta kernel: [    0.939283] ata1: SATA link up 
6.0 Gbps (SStatus 133 SControl 300)
Oct  2 16:36:37 txoritokieta kernel: [    0.939298] ata2: SATA link up 
6.0 Gbps (SStatus 133 SControl 300)
Oct  2 16:36:37 txoritokieta kernel: [    0.939311] ata3: SATA link up 
3.0 Gbps (SStatus 123 SControl 300)
Oct  2 16:36:37 txoritokieta kernel: [    0.939325] ata4: SATA link up 
3.0 Gbps (SStatus 123 SControl 300)
Oct  2 16:36:37 txoritokieta kernel: [    0.939947] ata1.00: ATA-9: WDC 
WD40EFRX-68WT0N0, 80.00A80, max UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.939950] ata1.00: 7814037168 
sectors, multi 16: LBA48 NCQ (depth 31/32), AA
Oct  2 16:36:37 txoritokieta kernel: [    0.940040] ata2.00: ATA-9: WDC 
WD40EFRX-68WT0N0, 82.00A82, max UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.940042] ata2.00: 7814037168 
sectors, multi 16: LBA48 NCQ (depth 31/32), AA
Oct  2 16:36:37 txoritokieta kernel: [    0.940763] ata1.00: configured 
for UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.940794] ata2.00: configured 
for UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.967864] ata4.00: ATA-8: 
ST31000333AS, SD15, max UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.967866] ata4.00: 1953525168 
sectors, multi 16: LBA48 NCQ (not used)
Oct  2 16:36:37 txoritokieta kernel: [    0.967867] ata4.00: WARNING: 
device requires firmware update to be fully functional
Oct  2 16:36:37 txoritokieta kernel: [    0.967868] ata4.00:          
contact the vendor or visit http://ata.wiki.kernel.org
Oct  2 16:36:37 txoritokieta kernel: [    0.967958] ata3.00: ATA-8: 
ST31000333AS, SD15, max UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    0.967962] ata3.00: WARNING: 
device requires firmware update to be fully functional
Oct  2 16:36:37 txoritokieta kernel: [    0.967963] ata3.00:          
contact the vendor or visit http://ata.wiki.kernel.org
Oct  2 16:36:37 txoritokieta kernel: [    1.009685] ata4.00: configured 
for UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    1.009771] ata3.00: configured 
for UDMA/133
Oct  2 16:36:37 txoritokieta kernel: [    1.340129] tsc: Refined TSC 
clocksource calibration: 2993.069 MHz
Oct  2 16:36:37 txoritokieta kernel: [    1.340131] Switching to 
clocksource tsc
Oct  2 16:36:37 txoritokieta kernel: [    1.398575] input: No brand KVM 
A1 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4/2-1.4:1.0/input/input2
Oct  2 16:36:37 txoritokieta kernel: [    1.398621] hid-generic 
0003:10D5:5B08.0001: input,hidraw0: USB HID v1.10 Keyboard [No brand KVM 
A1] on usb-0000:00:1d.0-1.4/input0
Oct  2 16:36:37 txoritokieta kernel: [    1.402843] input: No brand KVM 
A1 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4/2-1.4:1.1/input/input3
Oct  2 16:36:37 txoritokieta kernel: [    1.402891] hid-generic 
0003:10D5:5B08.0002: input,hidraw1: USB HID v1.10 Mouse [No brand KVM 
A1] on usb-0000:00:1d.0-1.4/input1
Oct  2 16:36:37 txoritokieta kernel: [   30.557549] megaraid_sas 
0000:01:00.0: Command timedoutfrom megasas_get_seq_num
Oct  2 16:36:37 txoritokieta kernel: [  189.678985] scsi 0:0:0:0: 
megasas: RESET cmd=3 retries=0
Oct  2 16:36:37 txoritokieta kernel: [  189.678988] megasas: [ 0]waiting 
for 2 commands to complete
Oct  2 16:36:37 txoritokieta kernel: [  194.682142] megasas: [ 5]waiting 
for 2 commands to complete
[...]
Oct  2 16:36:37 txoritokieta kernel: [  359.787412] megasas: 
[170]waiting for 2 commands to complete
Oct  2 16:36:37 txoritokieta kernel: [  364.790601] megasas: 
[175]waiting for 2 commands to complete
Oct  2 16:36:37 txoritokieta kernel: [  369.793795] megasas: moving 
cmd[0]:ffff88011517ff60:0:ffff8800363441c0 the defer queue as internal
Oct  2 16:36:37 txoritokieta kernel: [  369.793797] megasas: moving 
cmd[1]:ffff880036e72060:0:ffff8800363441c0 the defer queue as internal
Oct  2 16:36:37 txoritokieta kernel: [  369.793798] megaraid_sas: FW 
detected to be in faultstate, restarting it...
Oct  2 16:36:37 txoritokieta kernel: [  370.794428] ADP_RESET_GEN2: 
HostDiag=a0
Oct  2 16:36:37 txoritokieta kernel: [  380.791809] megaraid_sas: FW 
restarted successfully,initiating next stage...
Oct  2 16:36:37 txoritokieta kernel: [  380.791811] megaraid_sas: HBA 
recovery state machine,state 2 starting...
Oct  2 16:36:37 txoritokieta kernel: [  410.810946] megasas: Waiting for 
FW to come to ready state
Oct  2 16:36:37 txoritokieta kernel: [  410.874896] megasas: FW now in 
Ready state
Oct  2 16:36:37 txoritokieta kernel: [  410.916882] megaraid_sas: 
command ffff88011517ff60, ffff8800363441c0:0detected to be pending while 
HBA reset.
Oct  2 16:36:37 txoritokieta kernel: [  410.916884] megasas: 
ffff88011517ff60 scsi cmd [03]detected on the internal queue, issue again.
Oct  2 16:36:37 txoritokieta kernel: [  410.916885] megaraid_sas: 
command ffff880036e72060, ffff8800363441c0:0detected to be pending while 
HBA reset.
Oct  2 16:36:37 txoritokieta kernel: [  410.916886] megasas: 
ffff880036e72060 scsi cmd [03]detected on the internal queue, issue again.
Oct  2 16:36:37 txoritokieta kernel: [  410.916886] megaraid_sas: 
aen_cmd in def process
Oct  2 16:36:37 txoritokieta kernel: [  411.917519] megasas: reset 
successful
Oct  2 16:36:37 txoritokieta kernel: [  411.917524] scsi 0:0:0:0: 
megasas: RESET cmd=12 retries=0
Oct  2 16:36:37 txoritokieta kernel: [  411.917527] megaraid_sas: no 
pending cmds after reset
Oct  2 16:36:37 txoritokieta kernel: [  411.917528] megasas: reset 
successful
Oct  2 16:36:37 txoritokieta kernel: [  411.917599] scsi 0:0:0:0: 
megasas: RESET cmd=12 retries=0
Oct  2 16:36:37 txoritokieta kernel: [  411.917602] megaraid_sas: no 
pending cmds after reset
Oct  2 16:36:37 txoritokieta kernel: [  411.917603] megasas: reset 
successful
Oct  2 16:36:37 txoritokieta kernel: [  421.914965] scsi 0:0:0:0: 
megasas: RESET cmd=12 retries=0
Oct  2 16:36:37 txoritokieta kernel: [  421.914968] megaraid_sas: no 
pending cmds after reset
Oct  2 16:36:37 txoritokieta kernel: [  421.914969] megasas: reset 
successful
Oct  2 16:36:37 txoritokieta kernel: [  431.912330] scsi 0:0:0:0: Device 
offlined - not ready after error recovery
Oct  2 16:36:37 txoritokieta kernel: [  431.913728] scsi 0:0:1:0: 
Direct-Access     ATA      Crucial_CT128M55 MU01 PQ: 0 ANSI: 5
Oct  2 16:36:37 txoritokieta kernel: [  431.922178] scsi 1:0:0:0: 
Direct-Access     ATA      WDC WD40EFRX-68W 80.0 PQ: 0 ANSI: 5
Oct  2 16:36:37 txoritokieta kernel: [  431.922265] sd 1:0:0:0: [sdb] 
7814037168 512-byte logical blocks: (4.00 TB/3.63 TiB)
Oct  2 16:36:37 txoritokieta kernel: [  431.922267] sd 1:0:0:0: [sdb] 
4096-byte physical blocks
Oct  2 16:36:37 txoritokieta kernel: [  431.922285] sd 1:0:0:0: [sdb] 
Write Protect is off
Oct  2 16:36:37 txoritokieta kernel: [  431.922287] sd 1:0:0:0: [sdb] 
Mode Sense: 00 3a 00 00
Oct  2 16:36:37 txoritokieta kernel: [  431.922296] sd 1:0:0:0: [sdb] 
Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  2 16:36:37 txoritokieta kernel: [  431.922299] scsi 2:0:0:0: 
Direct-Access     ATA      WDC WD40EFRX-68W 82.0 PQ: 0 ANSI: 5
Oct  2 16:36:37 txoritokieta kernel: [  431.922398] sd 2:0:0:0: [sdc] 
7814037168 512-byte logical blocks: (4.00 TB/3.63 TiB)
Oct  2 16:36:37 txoritokieta kernel: [  431.922400] sd 2:0:0:0: [sdc] 
4096-byte physical blocks
Oct  2 16:36:37 txoritokieta kernel: [  431.922414] sd 2:0:0:0: [sdc] 
Write Protect is off
Oct  2 16:36:37 txoritokieta kernel: [  431.922415] sd 2:0:0:0: [sdc] 
Mode Sense: 00 3a 00 00
Oct  2 16:36:37 txoritokieta kernel: [  431.922422] sd 2:0:0:0: [sdc] 
Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  2 16:36:37 txoritokieta kernel: [  431.922542] scsi 3:0:0:0: 
Direct-Access     ATA      ST31000333AS     SD15 PQ: 0 ANSI: 5
Oct  2 16:36:37 txoritokieta kernel: [  431.922637] scsi 4:0:0:0: 
Direct-Access     ATA      ST31000333AS     SD15 PQ: 0 ANSI: 5
Oct  2 16:36:37 txoritokieta kernel: [  431.922688] sd 4:0:0:0: [sde] 
1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
Oct  2 16:36:37 txoritokieta kernel: [  431.922696] sd 3:0:0:0: [sdd] 
1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
Oct  2 16:36:37 txoritokieta kernel: [  431.922714] sd 4:0:0:0: [sde] 
Write Protect is off
Oct  2 16:36:37 txoritokieta kernel: [  431.922716] sd 4:0:0:0: [sde] 
Mode Sense: 00 3a 00 00
Oct  2 16:36:37 txoritokieta kernel: [  431.922719] sd 3:0:0:0: [sdd] 
Write Protect is off
Oct  2 16:36:37 txoritokieta kernel: [  431.922720] sd 3:0:0:0: [sdd] 
Mode Sense: 00 3a 00 00
Oct  2 16:36:37 txoritokieta kernel: [  431.922724] sd 4:0:0:0: [sde] 
Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  2 16:36:37 txoritokieta kernel: [  431.922731] sd 3:0:0:0: [sdd] 
Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  2 16:36:37 txoritokieta kernel: [  431.924245] sd 0:0:1:0: [sda] 
250069680 512-byte logical blocks: (128 GB/119 GiB)
Oct  2 16:36:37 txoritokieta kernel: [  431.924247] sd 0:0:1:0: [sda] 
4096-byte physical blocks
Oct  2 16:36:37 txoritokieta kernel: [  431.933715]  sdd: unknown 
partition table
Oct  2 16:36:37 txoritokieta kernel: [  431.934023] sd 3:0:0:0: [sdd] 
Attached SCSI disk
Oct  2 16:36:37 txoritokieta kernel: [  431.935504]  sde: unknown 
partition table
Oct  2 16:36:37 txoritokieta kernel: [  431.935588] sd 4:0:0:0: [sde] 
Attached SCSI disk
Oct  2 16:36:37 txoritokieta kernel: [  431.951835] sd 0:0:1:0: [sda] 
Write Protect is off
Oct  2 16:36:37 txoritokieta kernel: [  431.951837] sd 0:0:1:0: [sda] 
Mode Sense: 73 00 00 08
Oct  2 16:36:37 txoritokieta kernel: [  431.953077] sd 0:0:1:0: [sda] 
Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  2 16:36:37 txoritokieta kernel: [  431.966969] scanning ...
Oct  2 16:36:37 txoritokieta kernel: [  431.983106]  sda: unknown 
partition table
Oct  2 16:36:37 txoritokieta kernel: [  432.013232] sd 0:0:1:0: [sda] 
Attached SCSI disk
Oct  2 16:36:37 txoritokieta kernel: [  432.767089]  sdb: sdb1 sdb2 sdb3
Oct  2 16:36:37 txoritokieta kernel: [  432.767348] sd 1:0:0:0: [sdb] 
Attached SCSI disk
Oct  2 16:36:37 txoritokieta kernel: [  432.769250]  sdc: sdc1 sdc2
Oct  2 16:36:37 txoritokieta kernel: [  432.769497] sd 2:0:0:0: [sdc] 
Attached SCSI disk
Oct  2 16:36:37 txoritokieta kernel: [  432.773750] sd 0:0:1:0: Attached 
scsi generic sg0 type 0
Oct  2 16:36:37 txoritokieta kernel: [  432.773771] sd 1:0:0:0: Attached 
scsi generic sg1 type 0
Oct  2 16:36:37 txoritokieta kernel: [  432.773786] sd 2:0:0:0: Attached 
scsi generic sg2 type 0
Oct  2 16:36:37 txoritokieta kernel: [  432.773799] sd 3:0:0:0: Attached 
scsi generic sg3 type 0
Oct  2 16:36:37 txoritokieta kernel: [  432.773812] sd 4:0:0:0: Attached 
scsi generic sg4 type 0
[... mdadm --assemble -s ...]
Oct  2 16:36:37 txoritokieta kernel: [  487.916762] md: md0 stopped.
Oct  2 16:36:37 txoritokieta kernel: [  487.918069] md: bind<sdc2>
Oct  2 16:36:37 txoritokieta kernel: [  487.918153] md: bind<sdb2>
Oct  2 16:36:37 txoritokieta kernel: [  487.918833] md: raid10 
personality registered for level 10
Oct  2 16:36:37 txoritokieta kernel: [  487.919106] md/raid10:md0: 
active with 2 out of 2 devices
Oct  2 16:36:37 txoritokieta kernel: [  487.919192] created bitmap (8 
pages) for device md0
Oct  2 16:36:37 txoritokieta kernel: [  487.919353] md0: bitmap 
initialized from disk: read 1 pages, set 0 of 14800 bits
Oct  2 16:36:37 txoritokieta kernel: [  487.940935] md0: detected 
capacity change from 0 to 3972710531072
Oct  2 16:36:37 txoritokieta kernel: [  487.940948] RAID10 conf printout:
Oct  2 16:36:37 txoritokieta kernel: [  487.940950]  --- wd:2 rd:2
Oct  2 16:36:37 txoritokieta kernel: [  487.940951]  disk 0, wo:0, o:1, 
dev:sdb2
Oct  2 16:36:37 txoritokieta kernel: [  487.940953]  disk 1, wo:0, o:1, 
dev:sdc2
Oct  2 16:36:37 txoritokieta kernel: [  487.945789]  md0: unknown 
partition table

Any hint to fix this?

I have installed OMSA 7.4 but it doesn't seem to recognize H310 
controller, although it shows up in lspci:
01:00.0 RAID bus controller: LSI Logic / Symbios Logic MegaRAID SAS 2008 
[Falcon] (rev 03)

Thanks a lot
Eneko

-- 
Zuzendari Teknikoa / Director Técnico
Binovo IT Human Project, S.L.
Telf. 943575997
       943493611
Astigarraga bidea 2, planta 6 dcha., ofi. 3-2; 20180 Oiartzun (Gipuzkoa)
www.binovo.es



More information about the Linux-PowerEdge mailing list