call setup takes two tries?

Bogdan TARU bgd at icomag.de
Fri Aug 8 06:23:48 PDT 2003


	Hi everyone,

 First of all, I'm sorry for such a long mail and question list, but today
is the first day I'm playing with isdn on bsd.

 I am trying to dial into a dialin pool with an isdn modem, and the isdnd
log looks like:

08.08.2003 15:04:05 DBG setup_dialout: entry CGN_Pool1 ok!
08.08.2003 15:04:05 DBG find_by_device_for_dialout: found entry 0!
08.08.2003 15:04:05 CHD 00122 CGN_Pool1 rate 60 sec/unit (no ratefile)
08.08.2003 15:04:05 DBG sendm_connect_req: ctrl = 0, chan = -1
08.08.2003 15:04:05 DBG decr_free_channels: ctrl 0, now 1 chan free
08.08.2003 15:04:05 CHD 00122 CGN_Pool1 dialing out from 1234567 to
07654321
08.08.2003 15:04:05 DBG msg_teiasg_ind: unit 0, tei = 117
08.08.2003 15:04:05 DBG msg_l12stat_ind: unit 0, layer 2, state 1
08.08.2003 15:04:05 CHD 00122 CGN_Pool1 outgoing call disconnected
(remote)
08.08.2003 15:04:05 CHD 00122 CGN_Pool1 cause 100: Invalid information
element contents (Q.850)
08.08.2003 15:04:05 ERR set_channel_busy: controller [0] invalid channel
[-1]!
08.08.2003 15:04:05 DBG incr_free_channels: ctrl 0, now 2 chan free
08.08.2003 15:04:06 DBG setup_dialout: entry CGN_Pool1 ok!
08.08.2003 15:04:06 DBG find_by_device_for_dialout: found entry 0!
08.08.2003 15:04:06 CHD 00123 CGN_Pool1 rate 60 sec/unit (no ratefile)
08.08.2003 15:04:06 DBG sendm_connect_req: ctrl = 0, chan = -1
08.08.2003 15:04:06 DBG decr_free_channels: ctrl 0, now 1 chan free
08.08.2003 15:04:06 CHD 00123 CGN_Pool1 dialing out from 1234567 to
07654321
08.08.2003 15:04:06 DBG set_channel_busy: controller [0] channel B1 set to
BUSY!
08.08.2003 15:04:06 CHD 00123 CGN_Pool1 outgoing call proceeding (ctl 0,
ch 0)
08.08.2003 15:04:08 DBG set_channel_busy: controller [0] channel B1
already busy!
08.08.2003 15:04:08 CHD 00123 CGN_Pool1 outgoing call active (ctl 0, ch 0,
isp0)
08.08.2003 15:04:08 DBG isp0: switched to state 3

 Where '7654321' is the phone # I'm trying to reach (0 in front of it is
the dial-out code for the company's phone system), and the '1234567' is my
phone number.

 The isdntrace output looks like:


=========== isdntrace controller #0 =========== started Fri Aug  8
15:04:04 2003

-- TE->NT - unit:0 - frame:000314 - time:08.08 15:04:05.452731 - length:8
------
Dump:000  fc ff 03 0f 81 1a 01 ff                               ........
Q921: SAP=63 (TEI-Management), C, TEI=127, Ri=0x1a81, IdRequest, Ai=127

-- NT->TE - unit:0 - frame:000315 - time:08.08 15:04:05.482733 - length:8
------
Dump:000  fe ff 03 0f 00 00 06 d9                               ........
Q921: SAP=63 (TEI-Management), C, TEI=127, Ri=0x0000, IdRemove, Ai=108

-- NT->TE - unit:0 - frame:000316 - time:08.08 15:04:05.532735 - length:8
------
Dump:000  fe ff 03 0f 81 1a 02 eb                               ........
Q921: SAP=63 (TEI-Management), C, TEI=127, Ri=0x1a81, IdAssign, Ai=117

-- TE->NT - unit:0 - frame:000317 - time:08.08 15:04:05.532735 - length:3
------
Dump:000  00 eb 7f                                              ...
Q921: SAP=0 (Call Control), C, TEI=117, U-Frame: SABME PF 1

-- NT->TE - unit:0 - frame:000318 - time:08.08 15:04:05.602739 - length:3
------
Dump:000  00 eb 73                                              ..s
Q921: SAP=0 (Call Control), R, TEI=117, U-Frame: UA PF 1

-- TE->NT - unit:0 - frame:000319 - time:08.08 15:04:05.602739 - length:80
-----
Dump:000  00 eb 00 00                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 0 N(R) 0 P 0
Dump:004  08 01 1f 05 a1 04 02 88 90 18 01 83 6c 0b 81 32
............l..2
Dump:020  32 31 34 38 35 36 38 39 30 6d 02 80 28 70 0b 81
1234567m..(p..
Dump:036  30 30 32 32 31 34 32 32 39 30 71 20 80 3a 07 28
07654321q .:.(
Dump:052  d0 71 08 28 f4 f9 bf bf a4 54 07 28 8c 07 07 28
.q.(.....T.(...(
Dump:068  74 8b 32 32 31 34 38 35 36 38 39 30
t.1234567
Q931: pd=Q.931/I.451, cr=0x1f (from origination), message=SETUP:
     [sending complete]
     [bearer capability:
          cap=unrestricted digital information
          std=CCITT
          rate=64 kbit/s
          mode=circuit]
     [channel id: channel=any channel (preferred)]
     [calling party number: 123467 (type=unknown, plan=ISDN)]
     [calling party subaddress: LEN=0x02, DATA=0x80 0x28 ]
     [called party number: 07654321 (type=unknown, plan=ISDN)]
     [called party subaddress: LEN=0x20, DATA=0x80 0x3a 0x07 0x28 0xd0
0x71 0x08 0x28 0xf4 0xf9 0xbf 0xbf 0xa4 0x54 0x07 0x28 0x8c 0x07 0x07 0x28
0x74 0x8b 0x32 0x32
0x31 0x34 0x38 0x35 0x36 0x38 0x39 0x30 ]

-- NT->TE - unit:0 - frame:000320 - time:08.08 15:04:05.702743 - length:4
------
Dump:000  00 eb 01 02                                           ....
Q921: SAP=0 (Call Control), R, TEI=117, S-Frame: RR N(R) 1 PF 0

-- NT->TE - unit:0 - frame:000321 - time:08.08 15:04:05.742745 - length:12
-----
Dump:000  02 eb 00 02                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 0 N(R) 1 P 0
Dump:004  08 01 9f 5a 08 02 81 e4                               ...Z....
Q931: pd=Q.931/I.451, cr=0x1f (from destination), message=RELEASE
COMPLETE:
     [cause: 100: Invalid information element contents (Q.850)
          (location=private network serving local user, std=CCITT)]

-- TE->NT - unit:0 - frame:000322 - time:08.08 15:04:05.742745 - length:4
------
Dump:000  02 eb 01 02                                           ....
Q921: SAP=0 (Call Control), R, TEI=117, S-Frame: RR N(R) 1 PF 0

-- TE->NT - unit:0 - frame:000323 - time:08.08 15:04:06.462777 - length:53
-----
Dump:000  00 eb 02 02                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 1 N(R) 1 P 0
Dump:004  08 01 65 05 a1 04 02 88 90 18 01 83 6c 0b 81 32
..e.........l..2
Dump:020  32 31 34 38 35 36 38 39 30 6d 02 80 28 70 0b 81
1234567m..(p..
Dump:036  30 30 32 32 31 34 32 32 39 30 71 05 80 98 05 08
07654321q.....
Dump:052  04                                                    .
Q931: pd=Q.931/I.451, cr=0x65 (from origination), message=SETUP:
     [sending complete]
     [bearer capability:
          cap=unrestricted digital information
          std=CCITT
          rate=64 kbit/s
          mode=circuit]
     [channel id: channel=any channel (preferred)]
     [calling party number: 123467 (type=unknown, plan=ISDN)]
     [calling party subaddress: LEN=0x02, DATA=0x80 0x28 ]
     [called party number: 07654321 (type=unknown, plan=ISDN)]
     [called party subaddress: LEN=0x05, DATA=0x80 0x98 0x05 0x08 0x04 ]

-- NT->TE - unit:0 - frame:000324 - time:08.08 15:04:06.542781 - length:4
------
Dump:000  00 eb 01 04                                           ....
Q921: SAP=0 (Call Control), R, TEI=117, S-Frame: RR N(R) 2 PF 0

-- NT->TE - unit:0 - frame:000325 - time:08.08 15:04:06.562782 - length:11
-----
Dump:000  02 eb 02 04                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 1 N(R) 2 P 0
Dump:004  08 01 e5 02 18 01 89                                  .......
Q931: pd=Q.931/I.451, cr=0x65 (from destination), message=CALL PROCEEDING:
     [channel id: channel=B-1 (exclusive)]

-- TE->NT - unit:0 - frame:000326 - time:08.08 15:04:06.562782 - length:4
------
Dump:000  02 eb 01 04                                           ....
Q921: SAP=0 (Call Control), R, TEI=117, S-Frame: RR N(R) 2 PF 0

-- NT->TE - unit:0 - frame:000329 - time:08.08 15:04:08.442868 - length:15
-----
Dump:000  02 eb 04 04                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 2 N(R) 2 P 0
Dump:004  08 01 e5 07 29 05 03 08 08 0f 04
....)......
Q931: pd=Q.931/I.451, cr=0x65 (from destination), message=CONNECT:
     [date/time: 08.08.03 15:04]

-- TE->NT - unit:0 - frame:000330 - time:08.08 15:04:08.442868 - length:8
------
Dump:000  00 eb 04 06                                           ....
Q921: SAP=0 (Call Control), C, TEI=117, I-Frame: N(S) 2 N(R) 3 P 0
Dump:004  08 01 65 0f                                           ..e.
Q931: pd=Q.931/I.451, cr=0x65 (from origination), message=CONNECT
ACKNOWLEDGE:

-- NT->TE - unit:0 - frame:000331 - time:08.08 15:04:08.512871 - length:4
------
Dump:000  00 eb 01 06                                           ....
Q921: SAP=0 (Call Control), R, TEI=117, S-Frame: RR N(R) 3 PF 0

-- NT->TE - unit:0 - frame:000332 - time:08.08 15:04:10.102944 - length:4
------
Dump:000  02 db 01 83                                           ....
Q921: SAP=0 (Call Control), C, TEI=109, S-Frame: RR N(R) 65 PF 1

-- NT->TE - unit:0 - frame:000333 - time:08.08 15:04:10.182947 - length:4
------
Dump:000  00 db 01 83                                           ....
Q921: SAP=0 (Call Control), R, TEI=109, S-Frame: RR N(R) 65 PF 1


 I'm curious if this is the normal sequence of a dial in, since it seems
to me the first call fails. Could it be because of the bloody phone
central switch that allows me 'out' only with a '0' in front? Or could it
be the fault of the other end?

 The other curious thing is that I specified 'dialretries = 0' in
isdnd.rc, which should forbid the isdnd to dial after an unsuccesful try,
right? Then why does it try to establish the connection twice?

 Third question is, how can I get the peer-IP dynamically (for using it
as a default route)? Right now I've set it to a know value, since I
administer the dialin machine as well, but that won't be the case when I
dialin into a known ISP. What do I do then? Considering that I will get an
IP address, the tunnel will look like:

 my_assigned_IP ----> dummy_self_set_IP

 Should I set the assigned IP address as my default route?

 Please include me in the reply, since I'm not subscribed to the ml. Once
again, sorry for so many questions, and for your patience!

 Thanks,
 bogdan





More information about the freebsd-isdn mailing list