Skip site navigation (1)Skip section navigation (2)
Date:      Fri, 20 Feb 1998 16:41:07 +0100
From:      Aurelien Bargy <dj@dial.oleane.com>
To:        isdn@FreeBSD.ORG
Subject:   BISDN and FreeBSD 2.2.5-R
Message-ID:  <19980220164107.32385@abtl.le-bar.fdn.fr>

next in thread | raw e-mail | index | archive | help
Thanks a lot to all who tried to answer my question on the old list
"isdn@muc.ditec.de". I repeat the question for those who were not there :

I am trying to setup a FreeBSD 2.2.5-R box with a Teles S0/16.3 card inside
to connect to my ISP using synchronous PPP as I used to do with the same
machine under Linux. The problem is, when I ping a site, a dial command is
echoed, but it seems that no connection is established. I managed once or
twice to have it connected but the connection stopped immediately because my
ISP uses dynamic addressing and changing the IP address of the ppp0
interface made isdnpppd hang up the line.

I tried the "new" bisdntest_2 kernel patches archive, which docs precise
that the problem no longer exists... but there is no way to make it connect!
Since it works with other OSes (Linux, MS-Win...), I dont think the problem
is due to hardware, but rather software.

In fact I can't find a precise way to get things to work. Currently I do
this :

# sh /etc/rc.bisdn
# route add -interface default ppp0
# ifconfig ppp0 up
# ping www.freebsd.org

Here are the log files I can find. Messages are from the kernel and from
bisdnd. Thanks a lot again to anyone who could give me a hand :-)

0269854351 is my ISP number
0536421021 is my own phone number
......1020 is my ISDN line number
 

... and, sorry, some lines may be longer than 75 characters.

--------------------------------[starts here]------------------------------

At boot time :

Feb 13 00:57:13 abtl /kernel: tel0 at 0xd80 irq 5 on isa
Feb 13 00:57:13 abtl /kernel: IPP attach 0 f01c8e3c
Feb 13 00:57:13 abtl /kernel: IPP attach 1 f01c9e7c
Feb 13 00:57:13 abtl /kernel: tel0: card type Teles S0/16.3

When bisdnd starts with debug level 31 :

Feb 13 00:58:18 abtl bisdnd[187]: DBG rates: index 0 day 0 = 0.0-5.0:2400
[...more info on the rates...]
Feb 13 00:58:19 abtl bisdnd[187]: DBG rates: index 3 day 6 = 21.0-24.0:250
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: name = ISDNIP
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: interface = ipi0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: isdntype = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: reaction = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: protocol = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: telnloc-ldo = 0536421021
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: telnrem-ldo = 0269854351
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: telnloc-rdi = 1020
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: telnrem-rdi = *
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: controller = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: dialretries = 3
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: recoverytime = 3
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: callbackwait time = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: idletime-in = 360
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: idletime-out = 80
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: blocksize = 2048
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: unitlength-dyn = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: calledbackwait max time = 10
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 0: ratetyp = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no unitlength in entry 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no earlyhangup in entry 0, using 5
Feb 13 00:58:19 abtl bisdnd[187]: DBG unit length source = dynamically calculated
Feb 13 00:58:19 abtl bisdnd[187]: DBG device /dev/icard0 currently set to ISDN protocol DSS1, OK!
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: name = OLEANE
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: interface = ippp0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: isdntype = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: reaction = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: protocol = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: telnloc-ldo = 0536421021
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: telnrem-ldo = 0269854351
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: telnloc-rdi = 1020
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: telnrem-rdi = *
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: controller = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: dialretries = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: recoverytime = 5
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: callbackwait time = 5
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: idletime-in = 180
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: idletime-out = 120
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: blocksize = 1900
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: unitlength-dyn = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no calledbackwait max time in entry 1, using 10
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 1: ratetyp = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no unitlength in entry 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no earlyhangup in entry 1, using 5
Feb 13 00:58:19 abtl bisdnd[187]: DBG unit length source = dynamically calculated
Feb 13 00:58:19 abtl bisdnd[187]: DBG device /dev/icard0 currently set to ISDN protocol DSS1, OK!
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: name = TEL
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: interface = play
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: isdntype = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: reaction = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: protocol = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no telnloc-ldo in entry 2 (service=phone)
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no telnrem-ldo in entry 2 (service=phone)
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: telnloc-rdi = 1020
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: telnrem-rdi = *
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: controller = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no dialretries in entry 2, using 3
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no recoverytime in entry 2, using 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no callbackwait time in entry 2, using 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: idletime-in = 30
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no idletime-out in entry 2, using 60
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no blocksize in entry 2, using 2048
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no unitlength-dyn in entry 2, using 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no calledbackwait max time in entry 2, using 10
Feb 13 00:58:19 abtl bisdnd[187]: DBG entry 2: ratetyp = 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no unitlength in entry 2
Feb 13 00:58:19 abtl bisdnd[187]: DBG WARNING, no earlyhangup in entry 2, using 5
Feb 13 00:58:19 abtl bisdnd[187]: DBG unit length source = rate file entry 0
Feb 13 00:58:19 abtl bisdnd[187]: DBG device /dev/icard0 currently set to ISDN protocol DSS1, OK!
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: user specific ratesfile = /etc/bisdn/bisdnd.rates
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: user specific acctfile = /var/log/bisdnd.acct
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: user specific useacctfile = 1
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: write accounting info for all connections
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: no regular expressions defined
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: user defined playprog = play
Feb 13 00:58:19 abtl bisdnd[187]: DBG system: WARNING, cannot access user defined answerprog = answer
Feb 13 00:58:19 abtl bisdnd[187]: SYS daemon started (pid = 187)

When I try an analogic incoming call :

Feb 13 00:58:47 abtl bisdnd[187]: DBG BISDN: 'C'
Feb 13 00:58:47 abtl bisdnd[187]: DBG time < 1 - last 887327927:480745 now 887327927:480780
Feb 13 00:58:47 abtl bisdnd[187]: DBG BISDN: 'A^Y'
Feb 13 00:58:47 abtl /kernel: Assigned: TEI = 0x83 = 65
Feb 13 00:58:47 abtl bisdnd[187]: ACP T2 N0 C0/1 S1 incoming call (dst:1020 src:536421021)
Feb 13 00:58:47 abtl bisdnd[187]: DBG time < 1 - last 887327927:480745 now 887327927:508432
Feb 13 00:58:47 abtl bisdnd[187]: DBG BISDN: 'E^B'
Feb 13 00:58:47 abtl bisdnd[187]: DBG ERROR, connected() returned ERROR, active = 0!
Feb 13 00:58:47 abtl bisdnd[187]: CON T2 N0 C0/1 S1 connected [TEL]
Feb 13 00:58:47 abtl bisdnd[187]: DBG STATE change [msg_connect] IDLE --> CONNECTED
Feb 13 00:58:47 abtl bisdnd[187]: DBG time < 1 - last 887327927:480745 now 887327927:995869
Feb 13 00:58:52 abtl bisdnd[187]: DBG BISDN: 'F^B'
Feb 13 00:58:52 abtl bisdnd[187]: DIS T2 N0 C0/1 S1 disconnect (remote), cause=0x1f: Normal, unspecified
Feb 13 00:58:52 abtl bisdnd[187]: DBG STATE change [msg_disconnect] CONNECTED --> IDLE
Feb 13 00:58:52 abtl bisdnd[187]: DBG time OK! - last 887327927:480745 now 887327932:668944

OK, all seems to go well... Let's try now :

# route add -interface default ppp0
# ifconfig ppp0 up
# ping www.freebsd.org

Feb 13 00:59:02 abtl bisdnd[187]: DBG BISDN: 'B^C'
Feb 13 00:59:02 abtl bisdnd[187]: DBG STATE change [msg_dial] IDLE --> DIALING
Feb 13 00:59:02 abtl bisdnd[187]: DIA T3 N0 C0/- S0 dialed 0269854351
Feb 13 00:59:02 abtl bisdnd[187]: DBG time OK! - last 887327932:675308 now 887327942:78934
Feb 13 00:59:02 abtl bisdnd[187]: NDL T3 N0 C0/- S2 dial failed after 1 retries
Feb 13 00:59:02 abtl bisdnd[187]: DBG STATE change [handle_recovery] DIALING --> IDLE

Not enough retries allowed, maybe ? Let's do it again with 3 retries.

Feb 13 01:01:09 abtl bisdnd[214]: DBG entry 1: dialretries = 3
[...]

Another incoming call...

Feb 13 01:03:41 abtl bisdnd[175]: DBG BISDN: 'C'
Feb 13 01:03:41 abtl bisdnd[175]: DBG time < 1 - last 887328221:127245 now 887328221:127279
Feb 13 01:03:41 abtl bisdnd[175]: DBG BISDN: 'A^Y'
Feb 13 01:03:41 abtl /kernel: Assigned: TEI = 0x85 = 66
Feb 13 01:03:41 abtl bisdnd[175]: ACP T2 N0 C0/1 S1 incoming call (dst:1020 src:536421021)
Feb 13 01:03:41 abtl bisdnd[175]: DBG time < 1 - last 887328221:127245 now 887328221:156971
Feb 13 01:03:41 abtl bisdnd[175]: DBG BISDN: 'E^B'
Feb 13 01:03:41 abtl bisdnd[175]: DBG ERROR, connected() returned ERROR, active = 0!
Feb 13 01:03:41 abtl bisdnd[175]: CON T2 N0 C0/1 S1 connected [TEL]
Feb 13 01:03:41 abtl bisdnd[175]: DBG STATE change [msg_connect] IDLE --> CONNECTED
Feb 13 01:03:41 abtl bisdnd[175]: DBG time < 1 - last 887328221:127245 now 887328221:636785
Feb 13 01:03:49 abtl bisdnd[175]: DBG BISDN: 'F^B'
Feb 13 01:03:49 abtl bisdnd[175]: DIS T2 N0 C0/1 S1 disconnect (remote), cause=0x1f: Normal, unspecified
Feb 13 01:03:49 abtl bisdnd[175]: DBG STATE change [msg_disconnect] CONNECTED --> IDLE
Feb 13 01:03:49 abtl bisdnd[175]: DBG time OK! - last 887328221:127245 now 887328229:368312

OK, so now let's go with another ping :

Feb 13 01:04:10 abtl bisdnd[175]: DBG BISDN: 'B^C'
Feb 13 01:04:10 abtl bisdnd[175]: DBG STATE change [msg_dial] IDLE --> DIALING
Feb 13 01:04:10 abtl bisdnd[175]: DIA T3 N0 C0/- S0 dialed 0269854351
Feb 13 01:04:10 abtl bisdnd[175]: DBG time OK! - last 887328229:373755 now 887328250:195043

... Stuck ??? Let's try to call in :

Feb 13 01:04:33 abtl bisdnd[175]: DBG BISDN: 'C'
Feb 13 01:04:33 abtl bisdnd[175]: DBG time OK! - last 887328250:203449 now 887328273:608750
Feb 13 01:04:33 abtl bisdnd[175]: DBG BISDN: 'A^Y^?^A^A'
Feb 13 01:04:33 abtl bisdnd[175]: ACP T2 N0 C0/1 S1 incoming call (dst:1020 src:536421021)
Feb 13 01:04:33 abtl bisdnd[175]: DBG time < 1 - last 887328273:616769 now 887328273:633930

... but the system does not "talk" anymore (no "CONNECT TEL-IN" message).


--------------------------------[ends here]------------------------------

Best regards,
-- 
Aurélien Bargy ** dj@dial.oleane.com ** dj@estp.fr
Futur ex-pingouin émigrant

To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-isdn" in the body of the message



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?19980220164107.32385>