Persistent device names under Ubuntu Lucid LTS Server

Fri Jan 14 17:25:32 UTC 2011

On 01/14/2011 12:17 PM, carlopmart wrote:
> On 01/14/2011 05:44 PM, Joseph Salisbury wrote:
>>>>
>>>>
>>>
>>> Thanks Joseph .. It works ... But i have another problem. When I try to
>>> shutdown the server, open-iscsi never stops ... Is this a bug in
>>> open-iscsi package ??
>>>
>>>
>>>
>> Can you provide some additional details?  How are you shutting down the
>> server?  Are you using the shutdown command?
>>
>> Can you provide some messages from your log files(/var/log/syslog and/or
>> /var/log/kern.log).  Is the server not shutting down because open-iscsi
>> is not stopping?  Can you provide any errors you are seeing?
>>
>> Thanks,
>>
>> Joe
>>
> 
> Yes, I am shutting down th server with command "shutdown -h now". Afer
> that, server never shutdown because iscsid never stops.
> 
> From kern.log:
> 
> Jan 13 10:04:56 lorinand kernel: [    2.897799] e1000: eth1:
> e1000_probe: Intel(R) PRO/1000 Network Connection
> Jan 13 10:04:56 lorinand kernel: [    2.897902]   alloc irq_desc for 16
> on node -1
> Jan 13 10:04:56 lorinand kernel: [    2.897906]   alloc kstat_irqs on
> node -1
> Jan 13 10:04:56 lorinand kernel: [    2.897918] e1000 0000:02:02.0: PCI
> INT A -> GSI 16 (level, low) -> IRQ 16
> Jan 13 10:04:56 lorinand kernel: [    2.897997] e1000 0000:02:02.0:
> setting latency timer to 64
> Jan 13 10:04:56 lorinand kernel: [    3.265883] e1000: 0000:02:02.0:
> e1000_probe: (PCI:66MHz:32-bit) 00:50:56:12:b3:dd
> Jan 13 10:04:56 lorinand kernel: [    3.308890] e1000: eth2:
> e1000_probe: Intel(R) PRO/1000 Network Connection
> Jan 13 10:04:56 lorinand kernel: [    3.373256] scsi2 : ioc0: LSI53C1030
> B0, FwRev=01032920h, Ports=1, MaxQ=128, IRQ=17
> Jan 13 10:04:56 lorinand kernel: [    3.484799] scsi 2:0:0:0:
> Direct-Access VMware   Virtual disk     1.0  PQ: 0 ANSI: 2
> Jan 13 10:04:56 lorinand kernel: [    3.484812] scsi target2:0:0:
> Beginning Domain Validation
> Jan 13 10:04:56 lorinand kernel: [    3.485578] scsi target2:0:0: Domain
> Validation skipping write tests
> Jan 13 10:04:56 lorinand kernel: [    3.485582] scsi target2:0:0: Ending
> Domain Validation
> Jan 13 10:04:56 lorinand kernel: [    3.485646] scsi target2:0:0:
> FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 127)
> Jan 13 10:04:56 lorinand kernel: [    3.487629] sd 2:0:0:0: Attached
> scsi generic sg1 type 0
> Jan 13 10:04:56 lorinand kernel: [    3.487807] sd 2:0:0:0: [sda]
> 12582912 512-byte logical blocks: (6.44 GB/6.00 GiB)
> Jan 13 10:04:56 lorinand kernel: [    3.487837] sd 2:0:0:0: [sda] Write
> Protect is off
> Jan 13 10:04:56 lorinand kernel: [    3.487841] sd 2:0:0:0: [sda] Mode
> Sense: 03 00 00 00
> Jan 13 10:04:56 lorinand kernel: [    3.487872] sd 2:0:0:0: [sda] Cache
> data unavailable
> Jan 13 10:04:56 lorinand kernel: [    3.487875] sd 2:0:0:0: [sda]
> Assuming drive cache: write through
> Jan 13 10:04:56 lorinand kernel: [    3.488759] sd 2:0:0:0: [sda] Cache
> data unavailable
> Jan 13 10:04:56 lorinand kernel: [    3.488763] sd 2:0:0:0: [sda]
> Assuming drive cache: write through
> Jan 13 10:04:56 lorinand kernel: [    3.488768]  sda: sda1 sda2 sda3
> Jan 13 10:04:56 lorinand kernel: [    3.489615] sd 2:0:0:0: [sda] Cache
> data unavailable
> Jan 13 10:04:56 lorinand kernel: [    3.489619] sd 2:0:0:0: [sda]
> Assuming drive cache: write through
> Jan 13 10:04:56 lorinand kernel: [    3.489623] sd 2:0:0:0: [sda]
> Attached SCSI disk
> Jan 13 10:04:56 lorinand kernel: [    3.596371] EXT4-fs (dm-0): mounted
> filesystem with ordered data mode
> Jan 13 10:04:56 lorinand kernel: [    4.497941] udev: starting version 151
> Jan 13 10:04:56 lorinand kernel: [    4.503120] Adding 1465336k swap on
> /dev/sda2. Priority:-1 extents:1 across:1465336k
> Jan 13 10:04:56 lorinand kernel: [    4.846349] psmouse serio1: ID: 10
> 00 64
> Jan 13 10:04:56 lorinand kernel: [    4.856692] input: ImPS/2 Generic
> Wheel Mouse as /devices/platform/i8042/serio1/input/input3
> Jan 13 10:04:56 lorinand kernel: [    4.928870] parport_pc 00:08:
> reported by Plug and Play ACPI
> Jan 13 10:04:56 lorinand kernel: [    4.929238] parport0: PC-style at
> 0x378, irq 7 [PCSPP,TRISTATE]
> Jan 13 10:04:56 lorinand kernel: [    4.964452] shpchp: Standard Hot
> Plug PCI Controller Driver version: 0.4
> Jan 13 10:04:56 lorinand kernel: [    4.965449] Linux agpgart interface
> v0.103
> Jan 13 10:04:56 lorinand kernel: [    4.970921] ACPI: resource
> piix4_smbus [0x1040-0x1047] conflicts with ACPI region SMB_ [0x1040-0x104b]
> Jan 13 10:04:56 lorinand kernel: [    4.970926] ACPI: If an ACPI driver
> is available for this device, you should use it instead of the native
> driver
> Jan 13 10:04:56 lorinand kernel: [    4.974038] lp: driver loaded but no
> devices found
> Jan 13 10:04:56 lorinand kernel: [    4.985314] ppdev: user-space
> parallel port driver
> Jan 13 10:04:56 lorinand kernel: [    4.996846] agpgart-intel
> 0000:00:00.0: Intel 440BX Chipset
> Jan 13 10:04:56 lorinand kernel: [    4.997740] agpgart-intel
> 0000:00:00.0: AGP aperture is 256M @ 0x0
> Jan 13 10:04:56 lorinand kernel: [    4.998593] vga16fb: initializing
> Jan 13 10:04:56 lorinand kernel: [    4.998598] vga16fb: mapped to
> 0xc00a0000
> Jan 13 10:04:56 lorinand kernel: [    4.998644] fb0: VGA16 VGA frame
> buffer device
> Jan 13 10:04:56 lorinand kernel: [    5.024230] lp0: using parport0
> (interrupt-driven).
> Jan 13 10:04:56 lorinand kernel: [    5.316962] Console: switching to
> colour frame buffer device 80x30
> Jan 13 10:04:56 lorinand kernel: [    5.506094] e1000: eth0 NIC Link is
> Up 1000 Mbps Full Duplex, Flow Control: None
> Jan 13 10:04:56 lorinand kernel: [    5.741679] EXT4-fs (sda1): mounted
> filesystem with ordered data mode
> Jan 13 15:55:45 lorinand kernel: [21054.464789] Loading iSCSI transport
> class v2.0-870.
> Jan 13 15:55:45 lorinand kernel: [21054.849819] iscsi: registered
> transport (tcp)
> Jan 13 15:55:46 lorinand kernel: [21055.211105] iscsi: registered
> transport (iser)
> Jan 13 15:56:43 lorinand kernel: [21112.245175] e1000: eth1 NIC Link is
> Up 1000 Mbps Full Duplex, Flow Control: None
> Jan 13 15:58:21 lorinand kernel: [21210.822562] Loading iSCSI transport
> class v2.0-870.
> Jan 13 15:58:21 lorinand kernel: [21210.840291] iscsi: registered
> transport (tcp)
> Jan 13 15:58:21 lorinand kernel: [21210.886113] iscsi: registered
> transport (iser)
> Jan 13 17:12:29 lorinand kernel: [25658.849651] scsi3 : iSCSI Initiator
> over TCP/IP
> Jan 13 17:12:30 lorinand kernel: [25659.145737] scsi 3:0:0:0:
> RAID              IET      Controller       0001 PQ: 0 ANSI: 5
> Jan 13 17:12:30 lorinand kernel: [25659.147145] scsi 3:0:0:0: Attached
> scsi generic sg2 type 12
> Jan 13 17:12:30 lorinand kernel: [25659.151471] scsi 3:0:0:1:
> Direct-Access     IET      VIRTUAL-DISK     0001 PQ: 0 ANSI: 5
> Jan 13 17:12:30 lorinand kernel: [25659.152402] sd 3:0:0:1: Attached
> scsi generic sg3 type 0
> Jan 13 17:12:30 lorinand kernel: [25659.160269] sd 3:0:0:1: [sdb]
> 33554432 512-byte logical blocks: (17.1 GB/16.0 GiB)
> Jan 13 17:12:30 lorinand kernel: [25659.161099] sd 3:0:0:1: [sdb] Write
> Protect is off
> Jan 13 17:12:30 lorinand kernel: [25659.161104] sd 3:0:0:1: [sdb] Mode
> Sense: 49 00 00 08
> Jan 13 17:12:30 lorinand kernel: [25659.161418] sd 3:0:0:1: [sdb] Write
> cache: enabled, read cache: enabled, doesn't support DPO or FUA
> Jan 13 17:12:30 lorinand kernel: [25659.162564]  sdb: unknown partition
> table
> Jan 13 17:12:30 lorinand kernel: [25659.172179] sd 3:0:0:1: [sdb]
> Attached SCSI disk
> Jan 13 17:41:11 lorinand kernel: [27380.708024]  sdb: sdb1
> Jan 13 18:45:03 lorinand kernel: Kernel logging (proc) stopped.
> 
> 
> from syslog file:
> 
> Jan 13 15:41:34 lorinand ntpd[922]: kernel time sync status change 6001
> Jan 13 15:55:45 lorinand kernel: [21054.464789] Loading iSCSI transport
> class v2.0-870.
> Jan 13 15:55:45 lorinand kernel: [21054.849819] iscsi: registered
> transport (tcp)
> Jan 13 15:55:46 lorinand kernel: [21055.211105] iscsi: registered
> transport (iser)
> Jan 13 15:55:46 lorinand iscsid: iSCSI logger with pid=3743 started!
> Jan 13 15:55:46 lorinand iscsid: transport class version 2.0-870. iscsid
> version 2.0-871
> Jan 13 15:55:46 lorinand iscsid: iSCSI daemon with pid=3744 started!
> Jan 13 15:56:43 lorinand kernel: [21112.245175] e1000: eth1 NIC Link is
> Up 1000 Mbps Full Duplex, Flow Control: None
> Jan 13 15:56:43 lorinand init: ssh main process (732) terminated with
> status 255
> Jan 13 15:56:43 lorinand ntpd[922]: ntpd exiting on signal 15
> Jan 13 15:56:43 lorinand postfix/master[854]: reload -- version 2.7.0,
> configuration /etc/postfix
> Jan 13 15:56:48 lorinand ntpdate[3840]: step time server 131.234.137.23
> offset -0.014764 sec
> Jan 13 15:56:48 lorinand ntpd[3893]: ntpd 4.2.4p8 at 1.1612-o Fri Apr  9
> 00:28:40 UTC 2010 (1)
> Jan 13 15:56:48 lorinand ntpd[3894]: precision = 1.000 usec
> Jan 13 15:56:48 lorinand ntpd[3894]: ntp_io: estimated max descriptors:
> 1024, initial socket boundary: 16
> Jan 13 15:56:48 lorinand ntpd[3894]: Listening on interface #0 wildcard,
> 0.0.0.0#123 Disabled
> Jan 13 15:56:48 lorinand ntpd[3894]: Listening on interface #1 wildcard,
> ::#123 Disabled
> Jan 13 15:56:48 lorinand ntpd[3894]: Listening on interface #2 lo,
> 127.0.0.1#123 Enabled
> Jan 13 15:56:48 lorinand ntpd[3894]: Listening on interface #3 eth0,
> 172.25.70.20#123 Enabled
> Jan 13 15:56:48 lorinand ntpd[3894]: Listening on interface #4 eth1,
> 172.25.100.7#123 Enabled
> Jan 13 15:56:48 lorinand ntpd[3894]: kernel time sync status 2040
> Jan 13 15:56:48 lorinand ntpd[3894]: frequency initialized -102.479 PPM
> from /var/lib/ntp/ntp.drift
> Jan 13 15:58:21 lorinand kernel: [21210.822562] Loading iSCSI transport
> class v2.0-870.
> Jan 13 15:58:21 lorinand kernel: [21210.840291] iscsi: registered
> transport (tcp)
> Jan 13 15:58:21 lorinand kernel: [21210.886113] iscsi: registered
> transport (iser)
> Jan 13 15:58:21 lorinand iscsid: iSCSI logger with pid=3961 started!
> Jan 13 15:58:21 lorinand iscsid: transport class version 2.0-870. iscsid
> version 2.0-871
> Jan 13 15:58:21 lorinand iscsid: iSCSI daemon with pid=3962 started!
> Jan 13 16:01:06 lorinand ntpd[3894]: synchronized to 91.148.192.49,
> stratum 2
> Jan 13 16:01:06 lorinand ntpd[3894]: kernel time sync status change 2001
> Jan 13 16:05:24 lorinand ntpd[3894]: synchronized to 131.234.137.23,
> stratum 1
> Jan 13 16:17:01 lorinand CRON[4029]: (root) CMD (   cd / && run-parts
> --report /etc/cron.hourly)
> Jan 13 17:12:29 lorinand kernel: [25658.849651] scsi3 : iSCSI Initiator
> over TCP/IP
> Jan 13 17:12:30 lorinand kernel: [25659.145737] scsi 3:0:0:0:
> RAID              IET      Controller       0001 PQ: 0 ANSI: 5
> Jan 13 17:12:30 lorinand kernel: [25659.147145] scsi 3:0:0:0: Attached
> scsi generic sg2 type 12
> Jan 13 17:12:30 lorinand kernel: [25659.151471] scsi 3:0:0:1:
> Direct-Access     IET      VIRTUAL-DISK     0001 PQ: 0 ANSI: 5
> Jan 13 17:12:30 lorinand kernel: [25659.152402] sd 3:0:0:1: Attached
> scsi generic sg3 type 0
> Jan 13 17:12:30 lorinand kernel: [25659.160269] sd 3:0:0:1: [sdb]
> 33554432 512-byte logical blocks: (17.1 GB/16.0 GiB)
> Jan 13 17:12:30 lorinand kernel: [25659.161099] sd 3:0:0:1: [sdb] Write
> Protect is off
> Jan 13 17:12:30 lorinand kernel: [25659.161104] sd 3:0:0:1: [sdb] Mode
> Sense: 49 00 00 08
> Jan 13 17:12:30 lorinand kernel: [25659.161418] sd 3:0:0:1: [sdb] Write
> cache: enabled, read cache: enabled, doesn't support DPO or FUA
> Jan 13 17:12:30 lorinand kernel: [25659.162564]  sdb: unknown partition
> table
> Jan 13 17:12:30 lorinand kernel: [25659.172179] sd 3:0:0:1: [sdb]
> Attached SCSI disk
> Jan 13 17:12:30 lorinand iscsid: connection1:0 is operational now
> Jan 13 17:17:01 lorinand CRON[4141]: (root) CMD (   cd / && run-parts
> --report /etc/cron.hourly)
> Jan 13 17:26:54 lorinand ntpd[3894]: synchronized to 85.254.217.3,
> stratum 2
> Jan 13 17:41:11 lorinand kernel: [27380.708024]  sdb: sdb1
> Jan 13 17:43:50 lorinand ntpd[3894]: synchronized to 131.234.137.23,
> stratum 1
> Jan 13 18:17:01 lorinand CRON[4176]: (root) CMD (   cd / && run-parts
> --report /etc/cron.hourly)
> Jan 13 18:45:03 lorinand kernel: Kernel logging (proc) stopped.
> 
> 
>  sdb is th iscsi disk.
> 
>  When I launch "shutdown -h now" command last message is:
> 
>  connection1:0: ping timeout of 5 secs expired, recv timeout 5, last rx
> 34538, last ping 35788, now 37038
> 
>  ... and server never stops. It is installed under an ESXi 4.1 host.
> 
> Many thanks.

Can you do a tail -f /var/log/syslog, then issue the shutdown and send
the messages added to syslog after you started the tail?