SL9.1 Kanalbündelungs Problem (IRQ?)

26 Oct 2004

      Hallo Liste,

ich habe mit Yast Kanalbündelung aktiviert und kann diese mit KInternet auch 
ein/ausschalten. Leider ist die Datenrate mit zwei Kanälen NIEDRIGER!. Zum 
testen lasse ich einfach ein Filesharingprogramm laufen. Bei einem Kanal 
zeigt mir das "Datenrate" Fenster von kInternet ständig 8KB/s an. Schalte ich 
den zweiten Link hinzu geht die Rate in die Knie (starke Schwankungen und 
<6KB/s insgesamt).

Mit einem Kanal werden 8000-17000 BogoCPS mitgelogt mit 2 Kanälen sind es dann 
ca.2000+2000 BogoCPS

Hier ein Auszug der /var/log/message mit CAPI2.0 Treiber.
ReiserFS Einträge habe ich entfernt und meine MSN durch ein x ersetzt.
Was sagen euch diese Meldungen? Ein Problem mit Interrupts?
Habe dann mal auf den HiSax Treiber umgeschaltet. Da sehen die Meldungen 
ähnlich aus.

Gruß
Michael

CAPI2.0 Meldungen
------
Oct 24 14:31:03 linux kernel: ippp1: dialing 1 05112034743... 
Oct 24 14:31:03 linux isdnlog: Oct 24 14:31:03 * tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  RING (Data) 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  Time:Sun Oct 24 14:29:00 2004 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  CONNECT (Data) 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04 tei 99 calling +49 511/2034743, 
Hannover with +49 511/x, Hannover  INTERFACE ippp0 calling 05112034743 
Oct 24 14:31:04 linux kernel: capilib_new_ncci: kcapi: appl 1 ncci 0x20201 up
Oct 24 14:31:04 linux kernel: isdn_net: ippp1 connected
Oct 24 14:31:04 linux kernel: capidrv-1: chan 1 up with ncci 0x20201
Oct 24 14:31:04 linux ipppd[10512]: Local number: 0511x, Remote number: 
05112034743, Type: outgoing
Oct 24 14:31:04 linux ipppd[10512]: PHASE_WAIT -> PHASE_ESTABLISHED, ifunit: 
1, linkunit: 1, fd: 18
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  CHARGE: 5.997 /240s = 
1.499 /Min (DTAG T-ISDN Standard, City, Wochenende (Sonntag), Abend) 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  HINT: Better use 01013:Tele2 
Privat CbC, 0.990 /60s = 0.990 /Min, saving 1.187 /Min 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  1.CI 5.997  (now) 
Oct 24 14:31:04 linux isdnlog: Oct 24 14:31:04   tei 99 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  NEXT CI AFTER 04:00 (DTAG 
T-ISDN Standard, City, Wochenende (Sonntag), Abend) 
Oct 24 14:31:04 linux kernel: Badness in local_bh_enable at 
kernel/softirq.c:139
Oct 24 14:31:04 linux kernel: Call Trace:
Oct 24 14:31:04 linux kernel:  [local_bh_enable+72/96] 
local_bh_enable+0x48/0x60
Oct 24 14:31:04 linux kernel:  [<c0123398>] local_bh_enable+0x48/0x60
Oct 24 14:31:04 linux kernel:  [__crc_ide_xfer_verbose+190443/1505826] 
isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 14:31:04 linux kernel:  [<e2ddd2f5>] isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 14:31:04 linux kernel:  [__crc_ide_xfer_verbose+140129/1505826] 
isdn_net_start_xmit+0x6cb/0x7e0 [isdn]
Oct 24 14:31:04 linux kernel:  [<e2dd0e6b>] isdn_net_start_xmit+0x6cb/0x7e0 
[isdn]
Oct 24 14:31:04 linux kernel:  [__crc_soft_cursor+178343/3710424] 
Block_BTx+0x118/0x150 [fcpci]
Oct 24 14:31:04 linux kernel:  [<e2f6a968>] Block_BTx+0x118/0x150 [fcpci]
Oct 24 14:31:04 linux kernel:  [qdisc_restart+63/192] qdisc_restart+0x3f/0xc0
Oct 24 14:31:04 linux kernel:  [<c028ae9f>] qdisc_restart+0x3f/0xc0
Oct 24 14:31:04 linux kernel:  [net_tx_action+126/176] net_tx_action+0x7e/0xb0
Oct 24 14:31:04 linux kernel:  [<c027cb2e>] net_tx_action+0x7e/0xb0
Oct 24 14:31:04 linux kernel:  [__do_softirq+67/144] __do_softirq+0x43/0x90
Oct 24 14:31:04 linux kernel:  [<c01231e3>] __do_softirq+0x43/0x90
Oct 24 14:31:04 linux kernel:  [do_softirq+38/48] do_softirq+0x26/0x30
Oct 24 14:31:04 linux kernel:  [<c0123256>] do_softirq+0x26/0x30
Oct 24 14:31:04 linux kernel:  [do_IRQ+293/416] do_IRQ+0x125/0x1a0
Oct 24 14:31:04 linux kernel:  [<c010a8f5>] do_IRQ+0x125/0x1a0
Oct 24 14:31:04 linux kernel:  [common_interrupt+24/32] 
common_interrupt+0x18/0x20
Oct 24 14:31:04 linux kernel:  [<c0108d48>] common_interrupt+0x18/0x20

Oct 24 14:31:04 linux kernel: 
Oct 24 14:31:04 linux ipppd[10512]: ioctl(SIOCSIFMTU): No such device, 16 
ippp1 1500.
Oct 24 14:31:04 linux kernel: ippp0: all channels busy - requeuing!
Oct 24 14:31:04 linux kernel: Badness in local_bh_enable at 
kernel/softirq.c:139
Oct 24 14:31:04 linux kernel: Call Trace:
Oct 24 14:31:04 linux kernel:  [local_bh_enable+72/96] 
local_bh_enable+0x48/0x60
Oct 24 14:31:04 linux kernel:  [<c0123398>] local_bh_enable+0x48/0x60
Oct 24 14:31:04 linux kernel:  [__crc_ide_xfer_verbose+190443/1505826] 
isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 14:31:04 linux kernel:  [<e2ddd2f5>] isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 14:31:04 linux kernel:  [__crc_ide_xfer_verbose+140129/1505826] 
isdn_net_start_xmit+0x6cb/0x7e0 [isdn]
Oct 24 14:31:04 linux kernel:  [<e2dd0e6b>] isdn_net_start_xmit+0x6cb/0x7e0 
[isdn]
Oct 24 14:31:04 linux kernel:  [__crc_soft_cursor+185133/3710424] 
DataToUpperLayer+0x6e/0x130 [fcpci]
Oct 24 14:31:04 linux kernel:  [<e2f6c3ee>] DataToUpperLayer+0x6e/0x130 
[fcpci]
Oct 24 14:31:04 linux kernel:  [__crc_soft_cursor+185453/3710424] 
E1_RxData+0x7e/0x400 [fcpci]
Oct 24 14:31:04 linux kernel:  [<e2f6c52e>] E1_RxData+0x7e/0x400 [fcpci]
Oct 24 14:31:04 linux kernel:  [__crc_soft_cursor+179431/3710424] 
Block_BRx+0x118/0x160 [fcpci]
Oct 24 14:31:04 linux kernel:  [<e2f6ada8>] Block_BRx+0x118/0x160 [fcpci]
------

HiSax Meldungen
------
Oct 24 15:31:06 linux kernel: ippp1: dialing 1 05112034743... 
Oct 24 15:31:06 linux isdnlog: Oct 24 15:31:06 * tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  RING (Data) 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  Time:Sun Oct 24 15:29:00 2004 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  CONNECT (Data) 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07 tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  INTERFACE ippp0 calling 
05112034743 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  CHARGE: 5.997 /240s = 
1.499 /Min (DTAG T-ISDN Standard, City, Wochenende (Sonntag), Abend) 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  HINT: Better use 01013:Tele2 
Privat CbC, 0.990 /60s = 0.990 /Min, saving 1.187 /Min 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  1.CI 5.997  (now) 
Oct 24 15:31:07 linux isdnlog: Oct 24 15:31:07   tei 100 calling +49 
511/2034743, Hannover with +49 511/x, Hannover  NEXT CI AFTER 04:00 (DTAG 
T-ISDN Standard, City, Wochenende (Sonntag), Abend) 
Oct 24 15:31:08 linux kernel: isdn_net: ippp1 connected
Oct 24 15:31:08 linux ipppd[21929]: Local number: 0511x, Remote number: 
05112034743, Type: outgoing
Oct 24 15:31:08 linux ipppd[21929]: PHASE_WAIT -> PHASE_ESTABLISHED, ifunit: 
1, linkunit: 1, fd: 18
Oct 24 15:31:08 linux ipppd[21929]: ioctl(SIOCSIFMTU): No such device, 16 
ippp1 1500.
Oct 24 15:31:08 linux ipppd[21929]: MPPP negotiation, He: Yes We: Yes
Oct 24 15:31:08 linux ipppd[21929]: ipppd[1]: discr: 2 
Oct 24 15:31:08 linux ipppd[21929]: ipppd[1]: passed 1 
Oct 24 15:31:08 linux ipppd[21929]: ipppd[1]: passed 2 
Oct 24 15:31:08 linux ipppd[21929]: ipppd[1]: pap/chap-check passed 
Oct 24 15:31:08 linux ipppd[21929]: ok, found a valid bundle with linkunit 0
Oct 24 15:31:08 linux ipppd[21929]: bundle: 0
Oct 24 15:31:08 linux kernel: iPPP-bundle: minor: 1, slave unit: 1, master 
unit: 0
Oct 24 15:31:10 linux kernel: SFW2-INext-ACC-TCP IN=ippp0 OUT= MAC= 
SRC=63.202.108.250 DST=212.144.162.174 LEN=48 TOS=0x00 PREC=0x00 TTL=107 
ID=14801 DF PROTO=TCP SPT=2800 DPT=8080 WINDOW=65535 RES=0x00 SYN URGP=0 OPT 
(020405AC01010402) 
Oct 24 15:31:11 linux kernel: Badness in local_bh_enable at 
kernel/softirq.c:139
Oct 24 15:31:11 linux kernel: Call Trace:
Oct 24 15:31:11 linux kernel:  [local_bh_enable+72/96] 
local_bh_enable+0x48/0x60
Oct 24 15:31:11 linux kernel:  [<c0123398>] local_bh_enable+0x48/0x60
Oct 24 15:31:11 linux kernel:  [__crc_ide_xfer_verbose+190443/1505826] 
isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 15:31:11 linux kernel:  [<e2ddd2f5>] isdn_ppp_xmit+0x105/0x870 [isdn]
Oct 24 15:31:11 linux kernel:  [do_timer+47/1200] do_timer+0x2f/0x4b0
Oct 24 15:31:11 linux kernel:  [<c012667f>] do_timer+0x2f/0x4b0
Oct 24 15:31:11 linux kernel:  [__crc_ide_xfer_verbose+140129/1505826] 
isdn_net_start_xmit+0x6cb/0x7e0 [isdn]
Oct 24 15:31:11 linux kernel:  [<e2dd0e6b>] isdn_net_start_xmit+0x6cb/0x7e0 
[isdn]
Oct 24 15:31:11 linux kernel:  [__crc_ide_xfer_verbose+181686/1505826] 
isdn_timer_funct+0x0/0x130 [isdn]
Oct 24 15:31:11 linux kernel:  [<e2ddb0c0>] isdn_timer_funct+0x0/0x130 [isdn]
Oct 24 15:31:11 linux kernel:  [qdisc_restart+63/192] qdisc_restart+0x3f/0xc0
Oct 24 15:31:11 linux kernel:  [<c028ae9f>] qdisc_restart+0x3f/0xc0
Oct 24 15:31:11 linux kernel:  [net_tx_action+126/176] net_tx_action+0x7e/0xb0
Oct 24 15:31:11 linux kernel:  [<c027cb2e>] net_tx_action+0x7e/0xb0
Oct 24 15:31:11 linux kernel:  [__do_softirq+67/144] __do_softirq+0x43/0x90
Oct 24 15:31:11 linux kernel:  [<c01231e3>] __do_softirq+0x43/0x90
Oct 24 15:31:11 linux kernel:  [do_IRQ+293/416] do_IRQ+0x125/0x1a0
Oct 24 15:31:11 linux kernel:  [<c010a8f5>] do_IRQ+0x125/0x1a0
Oct 24 15:31:11 linux kernel:  [common_interrupt+24/32] 
common_interrupt+0x18/0x20
Oct 24 15:31:11 linux kernel:  [<c0108d48>] common_interrupt+0x18/0x20
Oct 24 15:31:11 linux kernel:  [ksoftirqd+0/160] ksoftirqd+0x0/0xa0
Oct 24 15:31:11 linux kernel:  [<c0123260>] ksoftirqd+0x0/0xa0
Oct 24 15:31:11 linux kernel:  [qdisc_restart+63/192] qdisc_restart+0x3f/0xc0
Oct 24 15:31:11 linux kernel:  [<c028ae9f>] qdisc_restart+0x3f/0xc0
Oct 24 15:31:11 linux kernel:  [net_tx_action+126/176] net_tx_action+0x7e/0xb0
Oct 24 15:31:11 linux kernel:  [<c027cb2e>] net_tx_action+0x7e/0xb0
Oct 24 15:31:11 linux kernel:  [__do_softirq+67/144] __do_softirq+0x43/0x90
Oct 24 15:31:11 linux kernel:  [<c01231e3>] __do_softirq+0x43/0x90
Oct 24 15:31:11 linux kernel:  [do_softirq+38/48] do_softirq+0x26/0x30
Oct 24 15:31:11 linux kernel:  [<c0123256>] do_softirq+0x26/0x30
Oct 24 15:31:11 linux kernel:  [ksoftirqd+89/160] ksoftirqd+0x59/0xa0
Oct 24 15:31:11 linux kernel:  [<c01232b9>] ksoftirqd+0x59/0xa0
Oct 24 15:31:11 linux kernel:  [kthread+169/209] kthread+0xa9/0xd1
Oct 24 15:31:11 linux kernel:  [<c012f839>] kthread+0xa9/0xd1
Oct 24 15:31:11 linux kernel:  [kthread+0/209] kthread+0x0/0xd1
Oct 24 15:31:11 linux kernel:  [<c012f790>] kthread+0x0/0xd1
Oct 24 15:31:11 linux kernel:  [kernel_thread_helper+5/16] 
kernel_thread_helper+0x5/0x10
Oct 24 15:31:11 linux kernel:  [<c0106005>] kernel_thread_helper+0x5/0x10
Oct 24 15:31:11 linux kernel: 
------

lspci -v zeigt
0000:01:09.0 Network controller: AVM Audiovisuelles MKTG & Computer System 
GmbH A1 ISDN [Fritz] (rev 02)
        Subsystem: AVM Audiovisuelles MKTG & Computer System GmbH FRITZ!Card 
ISDN Controller
        Flags: medium devsel, IRQ 12
        Memory at e3001000 (32-bit, non-prefetchable)
        I/O ports at a400 [size=32]

AthlonXP
DFI nForce2 Board
Suse9.1 mit 108er Kernel

SL9.1 Kanalbündelungs Problem (IRQ?)

Michael Born