Bug report

Although I did something less usual, I'll try to describe exactly what I 
did.
I had a target with two partitions active:

> Jul 11 19:42:33 green kernel: SCSI subsystem initialized
> Jul 11 19:42:33 green kernel: iscsi: registered transport (tcp)
> Jul 11 19:42:33 green iscsid: iSCSI logger with pid=4184 started!
> Jul 11 19:42:34 green iscsi-initiator: iscsid startup succeeded
> Jul 11 19:42:34 green iscsid: iSCSI daemon with pid=4185 started!
> Jul 11 19:42:34 green iscsid: version 0.3 variant (22-Apr-2005)
> Jul 11 19:43:01 green kernel: scsi0 : iSCSI Initiator over TCP/IP, v.0.3
> Jul 11 19:43:01 green kernel:   Vendor: IET       Model: 
> VIRTUAL-DISK      Rev: 0
> Jul 11 19:43:01 green kernel:   Type:   
> Direct-Access                      ANSI SCSI revision: 04
> Jul 11 19:43:02 green scsi.agent[4225]: disk at 
> /devices/platform/host0/target0:0:0/0:0:0:0
> Jul 11 19:43:02 green kernel: SCSI device sda: 237214720 512-byte hdwr 
> sectors (121454 MB)
> Jul 11 19:43:02 green kernel: SCSI device sda: drive cache: write back
> Jul 11 19:43:02 green kernel: SCSI device sda: 237214720 512-byte hdwr 
> sectors (121454 MB)
> Jul 11 19:43:02 green kernel: SCSI device sda: drive cache: write back
> Jul 11 19:43:02 green kernel:  sda: sda1 sda2
> Jul 11 19:43:02 green kernel: Attached scsi disk sda at scsi0, channel 
> 0, id 0, lun 0
> Jul 11 19:43:03 green iscsid: connection0:0 is operational now
I mounted /dev/sda2 under /media/cdrom (fedora core 3 with 2.6.12 
kernel). As this directory is monitored by the system I got the CDROM 
icon on my desktop (but remember it was the mounted scsi disk).
After copying etc I did a rightclick 'eject' command on this device. I 
presume a "eject /media/cdrom" command was send to the sda1 disk partition.
And now I got the following error:

> Jul 11 20:35:32 green kernel: kernel BUG at 
> /usr/src/redhat/BUILD/open-iscsi-0.3rc6-363/kernel/iscsi_tcp.c:1409!
> Jul 11 20:35:32 green kernel: invalid operand: 0000 [#1]
> Jul 11 20:35:32 green kernel: Modules linked in: vfat fat sd_mod 
> iscsi_tcp scsi_transport_iscsi scsi_mod parport_pc lp parport autofs4 
> sunrpc pcmcia video toshiba_acpi button battery ac md5 ipv6 
> yenta_socket rsrc_nonstatic pcmcia_core ohci_hcd i2c_ali1535 i2c_core 
> snd_ali5451 snd_ac97_codec snd_pcm_oss snd_mixer_oss snd_pcm snd_timer 
> snd soundcore snd_page_alloc ieee80211 ieee80211_crypt e100 mii 
> dm_snapshot dm_zero dm_mirror ext3 jbd dm_mod
> Jul 11 20:35:32 green kernel: CPU:    0
> Jul 11 20:35:32 green kernel: EIP:    0060:[<e7a3dfc8>]    Not tainted VLI
> Jul 11 20:35:32 green kernel: EFLAGS: 00010046   (2.6.12)
> Jul 11 20:35:32 green kernel: EIP is at iscsi_cmd_init+0x398/0x3b0 
> [iscsi_tcp]
> Jul 11 20:35:32 green kernel: eax: 00000000   ebx: d5cc1aa0   ecx: 
> 00000000   edx: 00000000
> Jul 11 20:35:32 green kernel: esi: 0000000a   edi: d5cc1ad0   ebp: 
> d599d720   esp: cffdad30
> Jul 11 20:35:32 green kernel: ds: 007b   es: 007b   ss: 0068
> Jul 11 20:35:32 green kernel: Process eject (pid: 4517, 
> threadinfo=cffda000 task=c16d3ae0)
> Jul 11 20:35:32 green kernel: Stack: 00000001 e674d604 db429200 
> d599d720 e674d604 db429200 00000000 e7a3f66d
> Jul 11 20:35:32 green kernel:        d599d784 00000246 00000246 
> d5cc1aa0 00000287 d599d720 e674d400 e7a6674a
> Jul 11 20:35:32 green kernel:        cffdad90 e66ce06c d599d720 
> ddf09000 e674d400 e66ce06c e7a6c9d4 c0238970
> Jul 11 20:35:32 green kernel: Call Trace:
> Jul 11 20:35:32 green kernel:  [<e7a3f66d>] 
> iscsi_queuecommand+0x10d/0x1b0 [iscsi_tcp]
> Jul 11 20:35:32 green kernel:  [<e7a6674a>] 
> scsi_dispatch_cmd+0x17a/0x2a0 [scsi_mod]
> Jul 11 20:35:32 green kernel:  [<e7a6c9d4>] 
> scsi_request_fn+0x1b4/0x370 [scsi_mod]
> Jul 11 20:35:32 green kernel:  [<c0238970>] blk_end_sync_rq+0x0/0x20
> Jul 11 20:35:32 green kernel:  [<c02357a5>] elv_next_request+0x85/0x150
> Jul 11 20:35:32 green kernel:  [<c0237a6c>] blk_remove_plug+0x2c/0x70
> Jul 11 20:35:32 green kernel:  [<c0238970>] blk_end_sync_rq+0x0/0x20
> Jul 11 20:35:32 green kernel:  [<c0237adb>] 
> __generic_unplug_device+0x2b/0x40
> Jul 11 20:35:32 green kernel:  [<c0237af6>] generic_unplug_device+0x6/0x10
> Jul 11 20:35:32 green kernel:  [<c023868b>] blk_execute_rq+0x9b/0xe0
> Jul 11 20:35:32 green kernel:  [<c023c14e>] scsi_cmd_ioctl+0x1de/0x460
> Jul 11 20:35:32 green kernel:  [<c015d350>] bdev_set+0x0/0x10
> Jul 11 20:35:32 green kernel:  [<e798fa3c>] sd_ioctl+0xfc/0x130 [sd_mod]
> Jul 11 20:35:32 green kernel:  [<c023a681>] blkdev_ioctl+0x141/0x420
> Jul 11 20:35:32 green kernel:  [<c015dce0>] block_ioctl+0x0/0x10
> Jul 11 20:35:32 green kernel:  [<c0167570>] do_ioctl+0x50/0x80
> Jul 11 20:35:32 green kernel:  [<c01676de>] vfs_ioctl+0x5e/0x1c0
> Jul 11 20:35:32 green kernel:  [<c01678b7>] sys_ioctl+0x77/0x90
> Jul 11 20:35:32 green kernel:  [<c0102e6f>] sysenter_past_esp+0x54/0x75
>
> > Jul 11 20:35:32 green kernel: Code: ff ff 8b 06 e8 ea 80 70 d8 89 43 
> > 64 8b 56 0c c7 47 04 ff ff ff ff 89 57 0c e9 68 ff ff ff 0f 0b 69 05 
> > cc 17 a4 e7 e9 93 fc ff ff <0f> 0b 81 05 cc 17 a4 e7 e9 15 fe ff ff 
> > 8d 74 26 00 8d bc 27 00
> > Jul 11 20:36:32 green kernel:  <6>iscsi0: detected conn error (1011)
> > Jul 11 20:36:33 green iscsid: detected iSCSI connection (handle 
> > 0xdb429200) error (1011) state (3)
> > Jul 11 20:36:33 green kernel: Unable to handle kernel paging request 
> > at virtual address fffffbe6
> > Jul 11 20:36:33 green kernel:  printing eip:
> > Jul 11 20:36:33 green kernel: fffffbe6
> > Jul 11 20:36:33 green kernel: *pde = 00002067
> > Jul 11 20:36:33 green kernel: Oops: 0000 [#2]
> > Jul 11 20:36:33 green kernel: Modules linked in: vfat fat sd_mod 
> > iscsi_tcp scsi_transport_iscsi scsi_mod parport_pc lp parport autofs4 
> > sunrpc pcmcia video toshiba_acpi button battery ac md5 ipv6 
> > yenta_socket rsrc_nonstatic pcmcia_core ohci_hcd i2c_ali1535 i2c_core 
> > snd_ali5451 snd_ac97_codec snd_pcm_oss snd_mixer_oss snd_pcm 
> > snd_timer snd soundcore snd_page_alloc ieee80211 ieee80211_crypt e100 
> > mii dm_snapshot dm_zero dm_mirror ext3 jbd dm_mod
> > Jul 11 20:36:33 green kernel: CPU:    0
> > Jul 11 20:36:33 green kernel: EIP:    0060:[<fffffbe6>]    Not 
> > tainted VLI
> > Jul 11 20:36:33 green kernel: EFLAGS: 00010097   (2.6.12)
> > Jul 11 20:36:33 green kernel: EIP is at 0xfffffbe6
> > Jul 11 20:36:33 green kernel: eax: c013e70e   ebx: c013e70e   ecx: 
> > 00000000   edx: 00000003
> > Jul 11 20:36:33 green kernel: esi: 89f989c3   edi: cffdae2c   ebp: 
> > e4dece4c   esp: e4dece2c
> > Jul 11 20:36:33 green kernel: ds: 007b   es: 007b   ss: 0068
> > Jul 11 20:36:33 green kernel: Process scsi_eh_0 (pid: 4198, 
> > threadinfo=e4dec000 task=d7154560)
> > Jul 11 20:36:33 green kernel: Stack: c0118167 00000000 e975c085 
> > 00000001 00000003 00000082 dbbcf600 00000286
> > Jul 11 20:36:33 green kernel:        e4dece60 c011826b 00000000 
> > 00000000 cc456d58 e66ce06c c0239a84 cc456d58
> > Jul 11 20:36:33 green kernel:        d599d720 e7a6bc73 00000000 
> > 00000000 00000000 d599d720 e7a6bfb5 00000000
> > Jul 11 20:36:33 green kernel: Call Trace:
> > Jul 11 20:36:33 green kernel:  [<c0118167>] __wake_up_common+0x37/0x70
> > Jul 11 20:36:33 green kernel:  [<c011826b>] complete+0x2b/0x40
> > Jul 11 20:36:33 green kernel:  [<c0239a84>] 
> > end_that_request_last+0x84/0xb0
> > Jul 11 20:36:33 green kernel:  [<e7a6bc73>] 
> > scsi_end_request+0x93/0xc0 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<e7a6bfb5>] 
> > scsi_io_completion+0x155/0x4f0 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<c01181ed>] __wake_up_locked+0x1d/0x20
> > Jul 11 20:36:33 green kernel:  [<e7a3f6d0>] 
> > iscsi_queuecommand+0x170/0x1b0 [iscsi_tcp]
> > Jul 11 20:36:33 green kernel:  [<e798feb4>] sd_rw_intr+0x164/0x320 
> > [sd_mod]
> > Jul 11 20:36:33 green kernel:  [<e7a69b97>] 
> > scsi_send_eh_cmnd+0x127/0x1a0 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<c012d5c0>] 
> > autoremove_wake_function+0x0/0x50
> > Jul 11 20:36:33 green kernel:  [<e7a66bf6>] 
> > scsi_finish_command+0x96/0xe0 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<e7a6adb7>] 
> > scsi_eh_flush_done_q+0xa7/0x100 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<e7a6ae8c>] 
> > scsi_unjam_host+0x7c/0x1b0 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<c0118120>] 
> > default_wake_function+0x0/0x10
> > Jul 11 20:36:33 green kernel:  [<e7a6b085>] 
> > scsi_error_handler+0xc5/0x160 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<e7a6afc0>] 
> > scsi_error_handler+0x0/0x160 [scsi_mod]
> > Jul 11 20:36:33 green kernel:  [<c010131d>] kernel_thread_helper+0x5/0x18
> > Jul 11 20:36:33 green kernel: Code:  Bad EIP value.
> > Jul 11 20:36:34 green iscsid: connection0:0 is operational after 
> > recovery (1 attempts)
> > Jul 11 20:36:58 green kernel:  <5>Synchronizing SCSI cache for disk sda:
I tried to logout the target but iscsiadm hang and I think the second 
oops above occurs, although the system stayed responsive and didn't freeze.
I hope this makes any sense to somebody?

Albert


-------------------------------------------------------
This SF.Net email is sponsored by the 'Do More With Dual!' webinar happening
July 14 at 8am PDT/11am EDT. We invite you to explore the latest in dual
core and dual graphics technology at this free one hour event hosted by HP, 
AMD, and NVIDIA.  To register visit http://www.hp.com/go/dualwebinar