Hi Eduard, Eduard Bloch Eduard Bloch <[EMAIL PROTECTED]>: > Erik Schanze schrieb am Donnerstag, den 06. Oktober 2005: > > Could you please do something on this bug, at least forward it > > upstream? > > > > It sucks really to have a modem, that cuts the internet connection > > randomly and silently. It could also be a hardware issue, but > > please give my some hints to debug it. (My warranty will expire in > > March 2006). > > Well, wait a second. If you have a real SmartLink modem, could you > try the latest upstream version available on http://www.smlink.com/? > I load slamr kernel module with debug=3 option and get following output in syslog: (I added some comments in +++ comment +++.) (There are messages : Oct 22 10:59:05 neo kernel: parport0: FIFO is stuck Oct 22 10:59:05 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio Oct 22 10:59:15 neo kernel: DMA write timed out that occur everytime and have something to do with kernel parport driver. This is an other story. ;-)
----------8<---------------------------------------------------------------------
+++ Start connecting with kppp +++
Oct 22 10:57:24 neo kernel: slamr: amrmo_ioctl: cmd 8, arg 0...
Oct 22 10:57:24 neo kernel: <p444.547165> amrmo_card_ctl...
Oct 22 10:57:24 neo kernel: <p444.547171> amrmo_card: SPEAKERVOL = 0
Oct 22 10:57:24 neo kernel: slamr: amrmo_ioctl: cmd 8, arg 0...
Oct 22 10:57:24 neo kernel: <p444.547186> amrmo_card_ctl...
Oct 22 10:57:24 neo kernel: <p444.547190> amrmo_card: SPEAKERVOL = 0
Oct 22 10:57:25 neo kernel: slamr: amrmo_ioctl: cmd 2, arg 1...
Oct 22 10:57:25 neo kernel: <p445.059767> amrmo_card_ctl...
Oct 22 10:57:25 neo kernel: <p445.059866> SIL3024codec::SetHookState(Offhook=1)
Oct 22 10:57:25 neo kernel: slamr: amrmo_ioctl: cmd 11, arg 0...
Oct 22 10:57:25 neo kernel: <p445.062151> amrmo_card_ctl...
Oct 22 10:57:25 neo kernel: slamr: amrmo_ioctl: cmd 4, arg 2580...
Oct 22 10:57:25 neo kernel: <p445.062252> amrmo_card_ctl...
Oct 22 10:57:25 neo kernel: slamr: amrmo_ioctl: cmd 7, arg 60...
Oct 22 10:57:25 neo kernel: <p445.062263> amrmo_card_ctl...
Oct 22 10:57:25 neo kernel: slamr: amrmo_ioctl: cmd 21, arg 0...
Oct 22 10:57:25 neo kernel: <p445.062274> amrmo_card_start...
Oct 22 10:57:25 neo kernel: <p445.062278> ModemInstance::Start()...
Oct 22 10:57:25 neo kernel: <p445.062284> [Modem]
Oct 22 10:57:25 neo kernel: <p445.062289> Rate = 9600 x M x 2
Oct 22 10:57:25 neo kernel: <p445.062294> Phys.PTC = 48 s. (96) b. ; Log.PTC =
48 s. (96) b.
Oct 22 10:57:25 neo kernel: <p445.062300> [LineIn]
Oct 22 10:57:25 neo kernel: <p445.062304> Rate = 9600 x S x 2
Oct 22 10:57:25 neo kernel: <p445.062309> Phys.PTC = 48 s. (192) b. ; Log.PTC =
48 s. (192) b.
Oct 22 10:57:25 neo kernel: <p445.062315> [LineOut]
Oct 22 10:57:25 neo kernel: <p445.062319> Rate = 9600 x S x 2
Oct 22 10:57:25 neo kernel: <p445.062324> Phys.PTC = 48 s. (192) b. ; Log.PTC =
48 s. (192) b.
Oct 22 10:57:25 neo kernel: <p445.062330> Starting Modio:
Oct 22 10:57:25 neo kernel: <p445.062334> PTC = (C)48, (P)192
Oct 22 10:57:25 neo kernel: <p445.062339> Sample Rates = (C)9600,(P)9600
Oct 22 10:57:25 neo kernel: <p445.062344> Transfer buffer of 48 sam. * 32 =
3072 b.
Oct 22 10:57:25 neo kernel: <p445.062351> PTC clock::Init(Buffer of C00h, PTC
of 96 bytes.)
Oct 22 10:57:25 neo kernel: <p445.062399> MTLHAL: Finish Modio Setup:
Oct 22 10:57:25 neo kernel: <p445.062404> VConverter::Test:=> InputRate =9600,
OutputRate =9600, bIsModem =1
Oct 22 10:57:25 neo kernel: <p445.062411> VConverter::Test:=> InputRate =9600,
OutputRate =9600, bIsModem =1
Oct 22 10:57:25 neo kernel: <p445.062417> MTLHAL: Mono sampling on output line!
Oct 22 10:57:25 neo kernel: <p445.062422> [LineOut]
Oct 22 10:57:25 neo kernel: <p445.062426> Rate = 9600 x M x 2
Oct 22 10:57:25 neo kernel: <p445.062431> Phys.PTC = 48 s. (96) b. ; Log.PTC =
48 s. (96) b.
Oct 22 10:57:25 neo kernel: <p445.062437> MTLHAL: Mono sampling on input line!
Oct 22 10:57:25 neo kernel: <p445.062442> [LineIn]
Oct 22 10:57:25 neo kernel: <p445.062445> Rate = 9600 x M x 2
Oct 22 10:57:25 neo kernel: <p445.062450> Phys.PTC = 48 s. (96) b. ; Log.PTC =
48 s. (96) b.
Oct 22 10:57:25 neo kernel: <p445.062461> Upon filter created(Simple):
9600/9600 spc, In 48 => Out 48
Oct 22 10:57:25 neo kernel: <p445.062474> Upon filter created(Simple):
9600/9600 spc, In 48 => Out 48
Oct 22 10:57:25 neo kernel: <p445.062487> LineOut forwarding <2>, written 192 b.
Oct 22 10:57:25 neo kernel: <p445.062492> <<MI>>
Oct 22 10:57:25 neo kernel: <p445.062496> [Source side]
Oct 22 10:57:25 neo kernel: <p445.062501> 9600 Spc, 1 channels, 16 bits
Oct 22 10:57:25 neo kernel: <p445.062506> Queue of 3840 b., Filter size 48 s.
Oct 22 10:57:25 neo kernel: <p445.062511> [Target side]
Oct 22 10:57:25 neo kernel: <p445.062515> 9600 Spc, 1 channels, 16 bits
Oct 22 10:57:25 neo kernel: <p445.062520> Queue of 3840 b., Filter size 48 s.
Oct 22 10:57:25 neo kernel: <p445.062525> [Filter](Simple): 9600/9600 spc, In
48 => Out 48
Oct 22 10:57:25 neo kernel: <p445.062531> <<MO>>
Oct 22 10:57:25 neo kernel: <p445.062535> [Source side]
Oct 22 10:57:25 neo kernel: <p445.062539> 9600 Spc, 1 channels, 16 bits
Oct 22 10:57:25 neo kernel: <p445.062544> Queue of 3840 b., Filter size 48 s.
Oct 22 10:57:25 neo kernel: <p445.062549> [Target side]
Oct 22 10:57:25 neo kernel: <p445.062553> 9600 Spc, 1 channels, 16 bits
Oct 22 10:57:25 neo kernel: <p445.062558> Queue of 3840 b., Filter size 48 s.
Oct 22 10:57:25 neo kernel: <p445.062563> [Filter](Simple): 9600/9600 spc, In
48 => Out 48
Oct 22 10:57:25 neo kernel: <p445.062729> Index 0, count 60, pos 0
Oct 22 10:57:25 neo kernel: <p445.062738> Start waiting from O96, I3072...
Oct 22 10:57:25 neo kernel: <p445.066227> VICHCard: Finished CAP loop...
Oct 22 10:57:25 neo kernel: <p445.066236> Modio opening (OK) in progress
Oct 22 10:57:25 neo kernel: <p445.066241> Buffers = (C)CD228000, (P)DE5C8000
Oct 22 10:57:25 neo kernel: <p445.066246> Size = (C)3072, (P)3072
Oct 22 10:57:37 neo kernel: slamr: amrmo_ioctl: cmd 10, arg 0...
Oct 22 10:57:37 neo kernel: <p457.087522> amrmo_card_ctl...
Oct 22 10:57:37 neo kernel: <p457.087530> MTLHAL: NTD query 83
Oct 22 10:57:37 neo kernel: <p457.087535> amrmo card: IODELAY = 336.
Oct 22 10:57:37 neo kernel: slamr: amrmo_ioctl: cmd 10, arg 0...
Oct 22 10:57:37 neo kernel: <p457.089211> amrmo_card_ctl...
Oct 22 10:57:37 neo kernel: <p457.089216> MTLHAL: NTD query 83
Oct 22 10:57:37 neo kernel: <p457.089221> amrmo card: IODELAY = 336.
Oct 22 10:57:45 neo kernel: parport0: FIFO is stuck
Oct 22 10:57:45 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio
Oct 22 10:57:54 neo pppd[9833]: pppd 2.4.3 started by es, uid 1000
Oct 22 10:57:54 neo pppd[9833]: using channel 5
Oct 22 10:57:54 neo pppd[9833]: Using interface ppp0
Oct 22 10:57:54 neo pppd[9833]: Connect: ppp0 <--> /dev/pts/0
Oct 22 10:57:54 neo pppd[9833]: sent [LCP ConfReq id=0x1 <mru 576> <asyncmap
0x0> <magic 0x80e4f70b> <pcomp> <accomp>]
Oct 22 10:57:55 neo kernel: DMA write timed out
Oct 22 10:57:56 neo pppd[9833]: rcvd [LCP ConfReq id=0x8e <asyncmap 0xa0000>
<auth chap MD5> <magic 0xa17c15e5> <pcomp> <accomp>]
Oct 22 10:57:56 neo pppd[9833]: sent [LCP ConfAck id=0x8e <asyncmap 0xa0000>
<auth chap MD5> <magic 0xa17c15e5> <pcomp> <accomp>]
Oct 22 10:57:57 neo pppd[9833]: sent [LCP ConfReq id=0x1 <mru 576> <asyncmap
0x0> <magic 0x80e4f70b> <pcomp> <accomp>]
Oct 22 10:57:57 neo pppd[9833]: rcvd [LCP ConfNak id=0x1 <mru 1500>]
Oct 22 10:57:57 neo pppd[9833]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic
0x80e4f70b> <pcomp> <accomp>]
Oct 22 10:57:57 neo pppd[9833]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0> <magic
0x80e4f70b> <pcomp> <accomp>]
Oct 22 10:57:57 neo pppd[9833]: rcvd [CHAP Challenge id=0x56
<21c6ac3210772c31cf434d2a9cb78e7f>, name = "dialoutuser"]
Oct 22 10:57:57 neo pppd[9833]: sent [CHAP Response id=0x56
<d1cc1e67b6b613d56f73b13268783e9e>, name = "arcor"]
Oct 22 10:57:57 neo pppd[9833]: rcvd [CHAP Success id=0x56 ""]
Oct 22 10:57:57 neo pppd[9833]: CHAP authentication succeeded
Oct 22 10:57:57 neo pppd[9833]: sent [CCP ConfReq id=0x1 <deflate 15>
<deflate(old#) 15> <bsd v1 15>]
Oct 22 10:57:57 neo pppd[9833]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01>
<addr 192.168.0.2> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Oct 22 10:57:57 neo pppd[9833]: rcvd [IPCP ConfReq id=0x1 <addr 145.253.1.105>]
Oct 22 10:57:57 neo pppd[9833]: sent [IPCP ConfAck id=0x1 <addr 145.253.1.105>]
Oct 22 10:57:57 neo pppd[9833]: rcvd [LCP ProtRej id=0x1 80 fd 01 01 00 0f 1a
04 78 00 18 04 78 00 15 03 2f]
Oct 22 10:57:57 neo pppd[9833]: Protocol-Reject for 'Compression Control
Protocol' (0x80fd) received
Oct 22 10:57:57 neo pppd[9833]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Oct 22 10:57:57 neo pppd[9833]: sent [IPCP ConfReq id=0x2 <addr 192.168.0.2>
<ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Oct 22 10:57:58 neo pppd[9833]: rcvd [IPCP ConfNak id=0x2 <addr 145.254.78.61>
<ms-dns1 195.50.140.114> <ms-dns3 145.253.2.203>]
Oct 22 10:57:58 neo pppd[9833]: sent [IPCP ConfReq id=0x3 <addr 145.254.78.61>
<ms-dns1 195.50.140.114> <ms-dns3 145.253.2.203>]
Oct 22 10:57:58 neo pppd[9833]: rcvd [IPCP ConfAck id=0x3 <addr 145.254.78.61>
<ms-dns1 195.50.140.114> <ms-dns3 145.253.2.203>]
Oct 22 10:57:58 neo pppd[9833]: local IP address 145.254.78.61
Oct 22 10:57:58 neo pppd[9833]: remote IP address 145.253.1.105
Oct 22 10:57:58 neo pppd[9833]: primary DNS address 195.50.140.114
Oct 22 10:57:58 neo pppd[9833]: secondary DNS address 145.253.2.203
Oct 22 10:57:58 neo pppd[9833]: Script /etc/ppp/ip-up started (pid 9851)
Oct 22 10:57:58 neo chronyd[4993]: Source 213.84.14.169 online
Oct 22 10:57:58 neo chronyd[4993]: Source 195.206.105.10 online
Oct 22 10:57:58 neo chronyd[4993]: Source 66.92.250.40 online
Oct 22 10:57:58 neo pppd[9833]: Script /etc/ppp/ip-up finished (pid 9851),
status = 0x0
Oct 22 10:57:59 neo wwwoffled[4604]: WWWOFFLE Connection from host
localhost.localdomain (127.0.0.1).
Oct 22 10:57:59 neo wwwoffled[4604]: WWWOFFLE Online.
Oct 22 10:57:59 neo wwwoffled[4604]: WWWOFFLE Connection from host
localhost.localdomain (127.0.0.1).
Oct 22 10:57:59 neo wwwoffled[4604]: WWWOFFLE Fetch.
Oct 22 10:58:09 neo wwwoffled[4604]: WWWOFFLE Fetch finished.
Oct 22 10:59:05 neo kernel: parport0: FIFO is stuck
Oct 22 10:59:05 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio
Oct 22 10:59:15 neo kernel: DMA write timed out
Oct 22 11:00:25 neo kernel: parport0: FIFO is stuck
Oct 22 11:00:25 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio
+++ The connection was cut here +++
Oct 22 11:00:30 neo kernel: slamr: amrmo_ioctl: cmd 22, arg 0...
Oct 22 11:00:30 neo kernel: <p630.187559> amrmo_card_stop...
Oct 22 11:00:30 neo kernel: <p630.187565> ModemInstance::Stop()...
Oct 22 11:00:30 neo kernel: <p630.187570> MTLHAL: Cleaning content of output
buffer(2688 of 2880)
Oct 22 11:00:30 neo kernel: <p630.187581> Converter [MI] is dead...
Oct 22 11:00:30 neo kernel: <p630.187588> Converter [MO] is dead...
Oct 22 11:00:30 neo kernel: slamr: amrmo_ioctl: cmd 2, arg 0...
Oct 22 11:00:30 neo kernel: <p630.187605> amrmo_card_ctl...
Oct 22 11:00:30 neo kernel: <p630.187615> SIL3024codec::SetHookState(Offhook=0)
Oct 22 11:00:35 neo kernel: DMA write timed out
Oct 22 11:01:45 neo wwwoffles[10097]: Unknown host 'www.debian.org' for server
[Connection timed out].
Oct 22 11:01:45 neo wwwoffles[10097]: Cannot open the HTTP connection to
www.debian.org port 80; [Connection timed out].
+++ I hang up with kppp +++
Oct 22 11:01:45 neo kernel: parport0: FIFO is stuck
Oct 22 11:01:45 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio
Oct 22 11:01:54 neo pppd[9833]: Terminating on signal 15
Oct 22 11:01:54 neo pppd[9833]: Connect time 4.0 minutes.
Oct 22 11:01:54 neo pppd[9833]: Sent 34455 bytes, received 85220 bytes.
Oct 22 11:01:54 neo pppd[9833]: Script /etc/ppp/ip-down started (pid 10168)
Oct 22 11:01:54 neo pppd[9833]: sent [LCP TermReq id=0x3 "User request"]
Oct 22 11:01:54 neo pppd[9833]: rcvd [LCP TermReq id=0x3 "User request"]
Oct 22 11:01:54 neo pppd[9833]: sent [LCP TermAck id=0x3]
Oct 22 11:01:54 neo wwwoffled[4604]: WWWOFFLE Connection from host
localhost.localdomain (127.0.0.1).
Oct 22 11:01:54 neo wwwoffled[4604]: WWWOFFLE Offline.
Oct 22 11:01:54 neo chronyd[4993]: Source 213.84.14.169 offline
Oct 22 11:01:54 neo chronyd[4993]: Source 195.206.105.10 offline
Oct 22 11:01:54 neo chronyd[4993]: Source 66.92.250.40 offline
Oct 22 11:01:55 neo pppd[9833]: Script /etc/ppp/ip-down finished (pid 10168),
status = 0x0
Oct 22 11:01:55 neo kernel: DMA write timed out
Oct 22 11:01:57 neo pppd[9833]: sent [LCP TermReq id=0x4 "User request"]
Oct 22 11:01:57 neo pppd[9833]: rcvd [LCP TermReq id=0x4 "User request"]
Oct 22 11:01:57 neo pppd[9833]: sent [LCP TermAck id=0x4]
Oct 22 11:01:57 neo pppd[9833]: rcvd [LCP TermAck id=0x4]
Oct 22 11:01:57 neo pppd[9833]: Connection terminated.
Oct 22 11:01:58 neo pppd[9833]: Exit.
Oct 22 11:03:05 neo kernel: parport0: FIFO is stuck
Oct 22 11:03:05 neo kernel: parport0: BUSY timeout (1) in compat_write_block_pio
----------8<--------------------------------------------------------------------------------
I hope that gives you possibility to solve this nasty bug.
Please feel free to ask for further testing.
Kindly regards
Erik
--
www.ErikSchanze.de *********************************************
Bitte keine HTML-E-Mails! No HTML mails, please! Limit: 100 kB *
* Linux-Info-Tag in Dresden, am 29. Oktober 2005 *
Info: http://www.linux-info-tag.de *
pgphPO9SQKNPs.pgp
Description: PGP signature
