Пожалуйста, войдите здесь. Часто задаваемые вопросы О нас
Задайте Ваш вопрос

История изменений [назад]

нажмите, чтобы скрыть/показать версии 1
изначальная версия
редактировать

спросил 2013-11-13 21:13:37 +0400

Ivanych Gravatar Ivanych

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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 

Кратко о системе: Asterisk 1.8.10.1 DAHDI Version: 2.5.0.1

Проблема довольно плавающая, звонки могут не рваться, а могут по 40с, 1,20 или 5м перед обрывом длиться.

Если чего то не хватает - выложу.

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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м перед обрывом длиться.

Если чего то не хватает - выложу.

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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]

Dahdi Обрыв звонков

Всем привет!

Есть сервер с е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

Блин, такое чувство что проблема очень простая, не могу догнать причину...

Проект компании "АТС Дизайн"
Asterisk® и Digium® являются зарегистрированными торговыми марками компании Digium, Inc., США.
IP АТС Asterisk распространяется под лицензией GNU GPL.