Всем привет!
Есть сервер с е1 картой, в которую заведен е1 поток от билайна. Есть проблема в виде периодически рвущихся звонков на сотовые телефоны при исходящей\входящей связи.
Долгая перипетия с билайном привела к тому что трассировка как с центрального коммутатора, так и с коммутатора, к которому мы напрямую подключены, с их слов говорит, что сессию рвём мы.
Ок, пытаюсь на сервере понять почему мы рвём, включил pri set debug 2 span 1.
Долго пытался отловить такой звонок, в тоге нашёл в логах следующее:
[Nov 13 15:10:24] DEBUG[31363] channel.c: Hanging up channel 'DAHDI/i1/89**-1010'
[Nov 13 15:10:24] DEBUG[31363] chan_dahdi.c: dahdi_hangup(DAHDI/i1/89*-1010)
[Nov 13 15:10:24] DEBUG[31363] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i1/89**-1010
[Nov 13 15:10:24] DEBUG[31363] sigpri.c: sigprihangup 1
[Nov 13 15:10:24] DEBUG[31363] sigpri.c: Not yet hungup... Calling hangup with cause 16, and clearing call
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 q931.c:6837 q931hangup: Hangup other cref:35321
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 q931.c:6594 _q931hangup: ourstate Active, peerstate Active, hold-state Idle
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 q931.c:5783 q931disconnect: Call 35321 enters state 11 (Disconnect Request). Hold state: Idle
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > DL-DATA request
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 2553/0x9F9) (Sent from originator)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Message Type: DISCONNECT (69)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=85, window is open V(A)=85 K=7
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI: 0 State 7(Multi-frame established)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > V(A)=85, V(S)=85, V(R)=108
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > K=7, RC=0, l3initiated=0, rejectexcept=0, ackpend=0
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > T200id=0, N200=3, T203id=8192
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [ 00 01 aa d8 08 02 09 f9 45 08 02 81 90 ]
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Informational frame:
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > SAPI: 00 C/R: 0 EA: 0
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI: 000 EA: 1
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > N(S): 085 0: 0
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > N(R): 108 P: 0
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > 9 bytes of data
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=9
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 2553/0x9F9) (Sent from originator)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Message Type: DISCONNECT (69)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [08 02 81 90]
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 -- Stopping T203 timer
[Nov 13 15:10:24] VERBOSE[31363] chandahdi.c: PRI Span: 1 -- Starting T200 timer
При этом у меня есть дамп sip сессии, где видно что bye прилетел от астера к нашему локальному телефону.
Кратко о системе:
Asterisk 1.8.10.1
DAHDI Version: 2.5.0.1
Проблема довольно плавающая, звонки могут не рваться, а могут по 40с, 1,20 или 5м перед обрывом длиться.
chan_dahdi.conf
[channels]
context=incoming
signalling=pri_cpe
switchtype=euroisdn ; euroisdn, national
pridialplan=unknown ; unknown, national
prilocaldialplan=unknown ; unknown, national
echocancel=yes
echocancelwhenbridged=yes
callerid=asreceived
callprogress=yes
relaxdtmf=yes group=1
channel=1-15,17-31
Для этого же звонка PRI SETUP
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > DL-DATA request
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=38
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 2553/0x9F9) (Sent from originator)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Message Type: SETUP (5)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 TEI=0 Transmitting N(S)=79, window is open V(A)=79 K=7
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI: 0 State 7(Multi-frame established)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > V(A)=79, V(S)=79, V(R)=100
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > K=7, RC=0, l3initiated=0, rejectexcept=0, ackpend=0
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > T200id=0, N200=3, T203id=8192
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [ 00 01 9e c8 08 02 09 f9 05 04 03 80 90 a3 18 03 a1 83 81 6c 06 00 81 34 32 31 37 70 0c 80 38 39 36 38 37 32 35 36 36 35 34 a1 ]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Informational frame:
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > SAPI: 00 C/R: 0 EA: 0
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI: 000 EA: 1
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > N(S): 079 0: 0
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > N(R): 100 P: 0
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > 38 bytes of data
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Protocol Discriminator: Q.931 (8) len=38
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 2553/0x9F9) (Sent from originator)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Message Type: SETUP (5)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [04 03 80 90 a3]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > User information layer 1: A-Law (35)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [18 03 a1 83 81]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Preferred Dchan: 0
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > ChanSel: As indicated in following octets
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Ext: 1 Coding: 0 Number Specified Channel Type: 3
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Ext: 1 Channel: 1 Type: CPE]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [6c 06 00 81 34 32 31 37]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Calling Number (len= 8) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Presentation: Presentation permitted, user number passed network screening (1) '4217' ]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [70 0c 80 38 39 36 38 37 32 35 36 36 35 34]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Called Number (len=14) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '89***' ]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > [a1]
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 > Sending Complete (len= 1)
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 -- Stopping T203 timer
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 -- Starting T200 timer
[Nov 13 15:09:26] VERBOSE[31363] chandahdi.c: PRI Span: 1 q931.c:6036 q931setup: Call 35321 enters state 1 (Call Initiated). Hold state: Idle
Что касается дампа звонка, со стороны SIP для локального телефона:
time [s],192.168.5.132,192.168.5.253,SIP message
0.000000,,,INVITE sip:989**@192.168.5.253 +SDP [0s]
0.000365,,,401 Unauthorized [0s]
0.016290,,,ACK sip:989*@192.168.5.253 [0s]
0.048336,,,INVITE sip:989*@192.168.5.253 +SDP [0s]
0.048968,,,100 Trying [0s]
0.361276,,,100 Trying [0.4s]
0.361348,,,183 Session Progress +SDP [0.4s]
0.370939,,,RTP G.711a SSRC:3f3d1b54 packets:2898 L:0 J:1.3
0.607208,,,RTP G.711a SSRC:135edfde packets:2881 L:1 J:18.8
4.962077,,,180 Ringing [5s]
19.397429,,,200 OK +SDP [19.4s]
19.496586,,,200 OK +SDP [19.5s]
19.696711,,,200 OK +SDP [19.7s]
19.779849,,,ACK sip:989*@192.168.5.253:5060 [19.8s]
19.795535,,,ACK sip:989*@192.168.5.253:5060 [19.8s]
19.809884,,,ACK sip:989**@192.168.5.253:5060 [19.8s]
58.369385,,,BYE sip:117@192.168.5.132:5060 [58.4s]
58.383334,,,200 OK [58.4s]
А вот так выглядят логи со стороны астера
[Nov 14 09:36:26] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag
[Nov 14 09:36:26] DEBUG[1706] chansip.c: Allocating new SIP dialog for a412f3ae-d2d3a091@192.168.5.112 - INVITE (No RTP)
[Nov 14 09:36:26] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag as122f513e
[Nov 14 09:36:26] DEBUG[1706] chansip.c: Stopping retransmission on 'a412f3ae-d2d3a091@192.168.5.112' of Response 101: Match Found
[Nov 14 09:36:26] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag
[Nov 14 09:36:39] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag as0b8968f4
[Nov 14 09:36:39] DEBUG[1706] chansip.c: Stopping retransmission on 'a412f3ae-d2d3a091@192.168.5.112' of Response 102: Match Found
[Nov 14 09:36:39] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag as0b8968f4
[Nov 14 09:36:39] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking From) --From tag 7e52a10a6cfa72e5o0 --To-tag as0b8968f4
[Nov 14 09:36:48] DEBUG[1706] chansip.c: = Looking for Call ID: a412f3ae-d2d3a091@192.168.5.112 (Checking To) --From tag as0b8968f4 --To-tag 7e52a10a6cfa72e5o0
[Nov 14 09:36:48] DEBUG[1706] chansip.c: Stopping retransmission on 'a412f3ae-d2d3a091@192.168.5.112' of Request 102: Match Found
[Nov 14 09:36:48] DEBUG[1706] chansip.c: Destroying SIP dialog a412f3ae-d2d3a091@192.168.5.112
Блин, такое чувство что проблема очень простая, не могу догнать причину...
смотрите в сторону наличия/отсутвия rtp потоков на сипе.
в sip.conf прописать для пира directmedia=no и в Dial - опции tT.
Задан: 2013-11-13 21:13:37 +0400
Просмотрен: 713 раз
Обновлен: Nov 19 '13
Проект компании "АТС Дизайн"
Asterisk® и Digium® являются зарегистрированными торговыми марками компании
Digium, Inc., США.
IP АТС Asterisk распространяется под лицензией
GNU GPL.
chan_dahdi.conf полный в студию. Также и SETUP в PRI при входящем и исходящем звонке неплохо было бы глянуть..
Zavr2008 ( 2013-11-13 23:55:12 +0400 )редактироватьзвонки к сожалению такие трудно выловить, но по этому выкладываю SETUP
Ivanych ( 2013-11-14 08:35:08 +0400 )редактировать> смотрите в сторону наличия/отсутствия rtp потоков на сипе. А подскажите более развёрнуто, о чём речь, пожалуйста?
Ivanych ( 2013-11-14 19:47:23 +0400 )редактировать