[Pkg-iscsi-maintainers] Bug#705162: iscsi_trgt: Kernel bug when squeeze iscsi-client reconnect after reboot.

Wed Apr 10 17:13:56 UTC 2013

Package: iscsitarget-dkms
Version: 1.4.20.2-10.1
Severity: important

Hello,

I've running two wheeze server as iscsi-target.
The iscsi-clients are squeeze server.
When I shutdown the client Server the iscsi-target running fine.
But after the reboot of the client the iscsi_trgt hang on both wheeze
servers with a Kernel Bug.
I can to reproduce this everytime when the open-iscsi startscript runs on
the squeeze server after a reboot.

-- System Information:
Debian Release: 7.0
  APT prefers testing
  APT policy: (500, 'testing')
Architecture: i386 (i686)

Kernel: Linux 3.2.0-4-686-pae (SMP w/24 CPU cores)
Locale: LANG=de_DE.UTF-8, LC_CTYPE=de_DE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages iscsitarget-dkms depends on:
ii  dkms  2.2.0.3-1.2
ii  make  3.81-8.2

Versions of packages iscsitarget-dkms recommends:
ii  linux-headers-3.2.0-4-686-pae [linux-headers]  3.2.41-2

iscsitarget-dkms suggests no packages.

-- Configuration Files:
/etc/default/iscsitarget:
ISCSITARGET_ENABLE=true

Target iqn.2013-02.erna-5:vg0
        Lun 1 Path=/dev/vg0/abacus,Type=blockio
        Lun 2 Path=/dev/vg0/abacus-swap,Type=blockio
        (...)
The real name in /dev/vg0 is a symlink to the Blocdevice.
i.e.
l /dev/vg0 | grep abacus
lrwxrwxrwx 1 root root 7 Apr 10 13:27 abacus -> ../dm-0
lrwxrwxrwx 1 root root 7 Apr 10 13:27 abacus-swap -> ../dm-1

The Symlink will be generated by udev roules whos a part of the xbd package.
Package-Source: deb http://de.bnix.de/b stable main

-- no debconf information

-- syslog
Apr 10 12:45:54 erna-5 kernel: [534048.645844]  connection1:0: ping timeout
of 5 secs expired, recv timeout 5, last rx 133431
004, last ping 133432256, now 133433508
Apr 10 12:45:54 erna-5 kernel: [534048.645938]  connection1:0: detected conn
error (1011)
Apr 10 12:45:56 erna-5 iscsid: Kernel reported iSCSI connection 1:0 error
(1011 - ISCSI_ERR_CONN_FAILED: iSCSI connection fai
led) state (3)
Apr 10 12:45:56 erna-5 kernel: [534050.252077] iscsi_trgt: BUG at
/var/lib/dkms/iscsitarget/1.4.20.2/build/kernel/iscsi.c:392 assert(req->tio)
Apr 10 12:45:56 erna-5 kernel: [534050.252176] Pid: 6051, comm: istiod1
/Tainted: G           O 3.2.0-4-686-pae #1 Debian 3.2.39-2
Apr 10 12:45:56 erna-5 kernel: [534050.252180] Call Trace:
Apr 10 12:45:56 erna-5 kernel: [534050.252198]  [<f60c9ffe>] ?
send_data_rsp+0x30/0x1ad [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.252208]  [<f60cf48b>] ?
is_volume_reserved+0x1b/0x3e [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.252217]  [<f60d07eb>] ?
disk_execute_cmnd+0x196/0x1e3 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.252225]  [<f60ccf20>] ?
worker_thread+0xcc/0x1e9 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.252234]  [<c1032109>] ?
try_to_wake_up+0x155/0x155
Apr 10 12:45:56 erna-5 kernel: [534050.252241]  [<f60cce54>] ?
nthread_stop+0x35/0x35 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.252247]  [<c104d297>] ?
kthread+0x63/0x68
Apr 10 12:45:56 erna-5 kernel: [534050.252252]  [<c104d234>] ?
kthread_worker_fn+0x101/0x101
Apr 10 12:45:56 erna-5 kernel: [534050.252260]  [<c12c727e>] ?
kernel_thread_helper+0x6/0x10
Apr 10 12:45:56 erna-5 kernel: [534050.252292] ------------[ cut here
]------------
Apr 10 12:45:56 erna-5 kernel: [534050.252337] kernel BUG at
/var/lib/dkms/iscsitarget/1.4.20.2/build/kernel/iscsi.c:392!
Apr 10 12:45:56 erna-5 kernel: [534050.252413] invalid opcode: 0000 [#1] SMP 
Apr 10 12:45:56 erna-5 kernel: [534050.252470] Modules linked in:
dm_snapshot xen_gntdev xen_evtchn xenfs iscsi_trgt(O) crc32c ib_iser rdma_cm
ib_addr iw_cm ib_cm ib_sa ib_mad ib_core iscsi_tcp libiscsi_tcp libiscsi
scsi_transport_iscsi nfsd nfs nfs_acl auth_rpcgss fscache lockd sunrpc 8021q
garp bonding bridge stp xfs loop coretemp crc32c_intel aesni_intel aes_i586
snd_pcm aes_generic snd_page_alloc snd_timer cryptd snd soundcore psmouse
serio_raw ioatdma pcspkr hpilo joydev evdev iTCO_wdt iTCO_vendor_support
hpwdt container acpi_power_meter button processor ext3 mbcache jbd dm_mod
raid1 md_mod sr_mod cdrom usbhid hid sg sd_mod crc_t10dif uhci_hcd ahci
libahci libata ehci_hcd usbcore usb_common thermal hpsa thermal_sys scsi_mod
igb dca [last unloaded: scsi_wait_scan]
Apr 10 12:45:56 erna-5 kernel: [534050.253500] 
Apr 10 12:45:56 erna-5 kernel: [534050.253538] Pid: 6051, comm: istiod1
Tainted: G           O 3.2.0-4-686-pae #1 Debian 3.2.39-2 HP ProLiant DL160
Gen8
Apr 10 12:45:56 erna-5 kernel: [534050.253639] EIP: 0061:[<f60c9ffe>]
EFLAGS: 00010292 CPU: 23
Apr 10 12:45:56 erna-5 kernel: [534050.253690] EIP is at
send_data_rsp+0x30/0x1ad [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.253738] EAX: 00000000 EBX: d9e94068
ECX: e46b9ea4 EDX: c137f2a8
Apr 10 12:45:56 erna-5 kernel: [534050.253787] ESI: 00000000 EDI: c2bb3100
EBP: c2bb3100 ESP: e46b9f48
Apr 10 12:45:56 erna-5 kernel: [534050.253839]  DS: 007b ES: 007b FS: 00d8
GS: 00e0 SS: 0069
Apr 10 12:45:56 erna-5 kernel: [534050.253887] Process istiod1 (pid: 6051,
ti=e46b8000 task=c2bb3100 task.ti=e46b8000)
Apr 10 12:45:56 erna-5 kernel: [534050.253961] Stack:
Apr 10 12:45:56 erna-5 kernel: [534050.253999]  f60d2769 f60d2731 00000188
f60d2b10 00000040 e5af7e00 e514b3c8 00000000
Apr 10 12:45:56 erna-5 kernel: [534050.254136]  00000000 e46b9f6c f60cf48b
e9f546c0 d9e94068 c2bb3100 c2bb3100 f60d07eb
Apr 10 12:45:56 erna-5 kernel: [534050.254271]  e9f546c0 e587f000 f60ccf20
e9f546d8 00000000 c2bb3100 c1032109 e4637fa4
Apr 10 12:45:56 erna-5 kernel: [534050.254407] Call Trace:
Apr 10 12:45:56 erna-5 kernel: [534050.254451]  [<f60cf48b>] ?
is_volume_reserved+0x1b/0x3e [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.254504]  [<f60d07eb>] ?
disk_execute_cmnd+0x196/0x1e3 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.254559]  [<f60ccf20>] ?
worker_thread+0xcc/0x1e9 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.254610]  [<c1032109>] ?
try_to_wake_up+0x155/0x155
Apr 10 12:45:56 erna-5 kernel: [534050.254659]  [<f60cce54>] ?
nthread_stop+0x35/0x35 [iscsi_trgt]
Apr 10 12:45:56 erna-5 kernel: [534050.254708]  [<c104d297>] ?
kthread+0x63/0x68
Apr 10 12:45:56 erna-5 kernel: [534050.254753]  [<c104d234>] ?
kthread_worker_fn+0x101/0x101
Apr 10 12:45:56 erna-5 kernel: [534050.254801]  [<c12c727e>] ?
kernel_thread_helper+0x6/0x10
Apr 10 12:45:56 erna-5 kernel: [534050.254847] Code: c3 83 ec 1c ff d2 8b 73
68 85 f6 75 20 68 10 2b 0d f6 68 88 01 00 00 68 31 27 0d f6 68 69 27 0d f6
e8 23 3c 1f cb e8 92 27 1f cb <0f> 0b 89 d8 e8 18 fd ff ff 8b 56 0c 39 d0 0f
46 d0 85 d2 0f 84 
Apr 10 12:45:56 erna-5 kernel: [534050.255440] EIP: [<f60c9ffe>]
send_data_rsp+0x30/0x1ad [iscsi_trgt] SS:ESP 0069:e46b9f48
Apr 10 12:45:56 erna-5 kernel: [534050.256018] ---[ end trace
201ce1ed8e6bad77 ]---
Apr 10 12:46:58 erna-5 kernel: [534111.997170] iscsi_trgt: Abort Task (01)
issued on tid:1 lun:1 by sid:1688849948148224 (Function Complete)