Re: [smartmontools-support] smartmontools 5.38+svn2920-2 failing at boot (but is OK when restarting it?)

[Justin P. <jp...@lu...>]

Any clues?

On Tue, 13 Oct 2009, Justin Piszcz wrote:

> Hello,
>
> I have 19 disks across 2 3ware 9650SE's (a 4PML and 16 port):
>
> Upon boot, I get the following:
>
> Oct 13 17:19:07 p34 smartd[2727]: smartd 5.39 2009-09-20 r2920 
> [x86_64-unknown-linux-gnu] (local build) Copyright (C) 2002-9 by Bruce Allen, 
> http://smartmontools.sourceforge.net
> Oct 13 17:19:07 p34 smartd[2727]: Opened configuration file /etc/smartd.conf
> Oct 13 17:19:07 p34 smartd[2727]: Configuration file /etc/smartd.conf parsed.
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_00], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_01], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_02], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_03], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_04], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_05], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_06], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_07], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_08], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_09], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_10], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_11], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_12], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_13], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_14], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa0 [3ware_disk_15], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa1 [3ware_disk_00], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa1 [3ware_disk_01], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Device: /dev/twa1 [3ware_disk_02], open() 
> failed: setup_3ware_nodes("twa", "3w-9xxx") failed
> Oct 13 17:19:07 p34 smartd[2727]: Unable to monitor any SMART enabled 
> devices. Try debug (-d) option. Exiting...
>
> But..
>
> After bootup, if I restart smartmontools:
>
> # /etc/init.d/smartmontools restart
> Restarting S.M.A.R.T. daemon: smartd
> #
>
> Oct 13 20:06:05 p34 smartd[13599]: smartd 5.39 2009-09-20 r2920 
> [x86_64-unknown-linux-gnu] (local build) Copyright (C) 2002-9 by Bruce Allen, 
> http://smartmontools.sourceforge.net
> Oct 13 20:06:05 p34 smartd[13599]: Opened configuration file /etc/smartd.conf
> Oct 13 20:06:05 p34 smartd[13599]: Configuration file /etc/smartd.conf 
> parsed.
> Oct 13 20:06:05 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_00], opened
> Oct 13 20:06:05 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_00], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_00], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_01], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_01], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_01], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_02], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_02], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_02], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_03], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_03], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_03], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_04], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_04], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_04], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_05], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_05], found 
> in smartd database.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_05], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_06], opened
> Oct 13 20:06:06 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_06], found 
> in smartd database.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_06], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_07], opened
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_07], found 
> in smartd database.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_07], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_08], opened
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_08], found 
> in smartd database.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_08], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_09], opened
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_09], found 
> in smartd database.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_09], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_10], opened
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_10], found 
> in smartd database.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_10], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_11], opened
> Oct 13 20:06:07 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_11], found 
> in smartd database.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_11], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_12], opened
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_12], found 
> in smartd database.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_12], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_13], opened
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_13], found 
> in smartd database.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_13], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_14], opened
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_14], found 
> in smartd database.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_14], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_15], opened
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_15], found 
> in smartd database.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_15], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_00], opened
> Oct 13 20:06:08 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_00], found 
> in smartd database.
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_00], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_01], opened
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_01], found 
> in smartd database.
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_01], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_02], opened
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_02], found 
> in smartd database.
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_02], is 
> SMART capable. Adding to "monitor" list.
> Oct 13 20:06:09 p34 smartd[13599]: Monitoring 19 ATA and 0 SCSI devices
> Oct 13 20:06:09 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_00], initial 
> Temperature is 35 Celsius (Min/Max ??/35)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_01], initial 
> Temperature is 35 Celsius (Min/Max ??/35)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_02], initial 
> Temperature is 35 Celsius (Min/Max ??/35)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_03], initial 
> Temperature is 35 Celsius (Min/Max ??/35)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_04], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_05], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_06], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_07], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_08], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_09], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:10 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_10], initial 
> Temperature is 35 Celsius (Min/Max ??/35)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_11], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_12], initial 
> Temperature is 33 Celsius (Min/Max ??/33)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_13], initial 
> Temperature is 33 Celsius (Min/Max ??/33)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_14], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa0 [3ware_disk_15], initial 
> Temperature is 34 Celsius (Min/Max ??/34)
> Oct 13 20:06:11 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_00], initial 
> Temperature is 38 Celsius (Min/Max ??/38)
> Oct 13 20:06:12 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_01], initial 
> Temperature is 37 Celsius (Min/Max ??/37)
> Oct 13 20:06:12 p34 smartd[13599]: Device: /dev/twa1 [3ware_disk_02], initial 
> Temperature is 37 Celsius (Min/Max ??/37)
> Oct 13 20:06:12 p34 smartd[13601]: smartd has fork()ed into background mode. 
> New PID=13601.
> Oct 13 20:06:12 p34 smartd[13601]: file /var/run/smartd.pid written 
> containing PID 13601
>
> Then it is successful.
>
> Anyone know what is happening here?
>
> Is this a udev/timing issue?
>
> Has anyone had a similar issue?
>
> Justin.
>