VIDEOCHAT  ::   FAQ  ::   Поиск  ::   Регистрация  ::   Вход

Увеличивается задержка (Peer is Lagged) после звонка.

Проблемы Asterisk без вэб-оболочек и их решения

Модераторы: april22, Zavr2008

drTr0jan
Сообщения: 20
Зарегистрирован: 14 мар 2011, 16:51
Откуда: Хабаровск, РФ
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение drTr0jan »

Vlad1983
Тему с самого начала сложно прочитать? В заглавном посте написано, что это делалось. В третьем посте темы написано, к чему это приводит.

Sfinx
Не позорьте себя и своего работодателя.
1) Изучите матчасть, и узнайте что такое NAT.
2) Изучите, зачем нужны серые адреса (172.16.0.0/12).
3) Даже если там и был бы мифический NAT. Почему в первом случае (когда удалённый абонент кладёт трубку, или когда авторизуешься на софтсвиче по логину и паролю) всё работает, а во втором случае (когда трубку кладу я) сыпется предупреждение? Да, и расскажите мне, как убрать NAT (если всё же вы его видите), чтобы его не было.
Vlad1983
Сообщения: 4251
Зарегистрирован: 09 авг 2011, 11:51

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение Vlad1983 »

drTr0jan писал(а):В заглавном посте написано, что это делалось. В третьем посте темы написано, к чему это приводит.
делалось ХЗ где, но не там где нужно

Код: Выделить всё

asterisk -rx "sip show peers" | grep ttk
ЛС: @rostel
drTr0jan
Сообщения: 20
Зарегистрирован: 14 мар 2011, 16:51
Откуда: Хабаровск, РФ
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение drTr0jan »

Vlad1983
Почему сразу "ХЗ где"? :) Как раз в настройках пира ttk в sip.conf.

qualify=no

Код: Выделить всё

tracer-tong:/home/drtr0jan# asterisk -rx "sip show peers" | grep ttk
ttk                        172.31.1.34                                      A  5060     Unmonitored
После трёх звонков с моим hangup имеем:

Код: Выделить всё

tracer-tong:/usr/local/etc# tail -n 3 /var/log/asterisk/messages
[Jan 11 21:20:05] NOTICE[38658] chan_sip.c: Peer 'ttk' is now Lagged. (111320ms / 0ms)
[Jan 11 21:20:28] NOTICE[38658] chan_sip.c: Peer 'ttk' is now Lagged. (133810ms / 0ms)
[Jan 11 21:20:52] NOTICE[38658] chan_sip.c: Peer 'ttk' is now Lagged. (158240ms / 0ms)
tma
Сообщения: 1809
Зарегистрирован: 18 сен 2010, 20:50
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение tma »

drTr0jan писал(а): Во время простоя задержка (qualify) прыгает с 2 до 15 мс. И только после завершения разговора (при вышеописанных условиях) подрастает до 1000-60000 мс (в зависимости от длительности разговора).
Может HuaweiSoftX3000 после завершения разговора какое-то время не отвечает на OPTIONS?
Интересно бы снифером проверить, отключает ли quality=no отправку OPTIONS или нет. Может баг какой-нибудь.
Конечно все это только предположение.
SkyTel OU - облачная АТС, DID, SIP-транк с посекундной тарификаицей, мобильная связь
http://skytel24.com | Эстония: +372.333.55.10 | Россия: +7(495)4019900
ded
Сообщения: 15620
Зарегистрирован: 26 авг 2010, 19:00

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение ded »

Во время звока через ttk когда Lagged. 111320ms
сделайте mtr 172.31.1.3 чтобы увидеть на каком узле трассы создаётся задержка.
tma
Сообщения: 1809
Зарегистрирован: 18 сен 2010, 20:50
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение tma »

Было бы странно, если там создавалась задержка, т.к. как я понял, она проявляется только при разрыве с одной стороны, а не с произвольной после каждого вызова.
SkyTel OU - облачная АТС, DID, SIP-транк с посекундной тарификаицей, мобильная связь
http://skytel24.com | Эстония: +372.333.55.10 | Россия: +7(495)4019900
drTr0jan
Сообщения: 20
Зарегистрирован: 14 мар 2011, 16:51
Откуда: Хабаровск, РФ
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение drTr0jan »

tma писал(а):Может HuaweiSoftX3000 после завершения разговора какое-то время не отвечает на OPTIONS?
Интересно бы снифером проверить, отключает ли quality=no отправку OPTIONS или нет. Может баг какой-нибудь.
Конечно все это только предположение.
Да отвечает нормально.
Зачем сниффером? Вот sip set debug peer ttk снял с qualify=no (в заглавном посте - с qualify=yes):

Код: Выделить всё

    -- Executing [420000@office:1] Dial("SIP/201-00000199", "SIP/ttk/420000,,T") in new stack
Audio is at 5060
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 172.31.1.34:5060:
INVITE sip:420000@172.31.1.34 SIP/2.0
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK39c629e0
Max-Forwards: 70
From: "Test2" <sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>
Contact: <sip:910000@172.30.1.206:5060>
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 1.8.8.0
Date: Mon, 09 Jan 2012 13:47:32 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 260

v=0
o=root 1333822630 1333822630 IN IP4 172.30.1.206
s=Asterisk PBX 1.8.8.0
c=IN IP4 172.30.1.206
t=0 0
m=audio 19164 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

---
    -- Called SIP/ttk/420000

<--- SIP read from UDP:172.31.1.34:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK39c629e0
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
From: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>
CSeq: 102 INVITE
Content-Length: 0

<------------->
--- (7 headers 0 lines) ---

<--- SIP read from UDP:172.31.1.34:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK39c629e0
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
From: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>;tag=a0a610ac
CSeq: 102 INVITE
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Contact: <sip:420000@172.31.1.34:5060;user=phone>
Content-Length: 203
Content-Type: application/sdp

v=0
o=HuaweiSoftX3000 9874975 9874975 IN IP4 172.31.1.34
s=Sip Call
c=IN IP4 172.31.1.34
t=0 0
m=audio 32256 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (10 headers 9 lines) ---
Found RTP audio format 0
Found RTP audio format 101
Found audio description format PCMU for ID 0
Found audio description format telephone-event for ID 101
Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 172.31.1.34:32256
    -- SIP/ttk-0000019a is ringing
    -- SIP/ttk-0000019a is making progress passing it to SIP/201-00000199

<--- SIP read from UDP:172.31.1.34:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK39c629e0
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
From: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>;tag=a0a610ac
CSeq: 102 INVITE
Contact: <sip:420000@172.31.1.34:5060;user=phone>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REGISTER,PRACK,INFO,UPDATE,SUBSCRIBE,NOTIFY,MESSAGE,REFER,PUBLISH
Content-Length: 203
Content-Type: application/sdp

v=0
o=HuaweiSoftX3000 9874975 9874976 IN IP4 172.31.1.34
s=Sip Call
c=IN IP4 172.31.1.34
t=0 0
m=audio 32256 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (10 headers 9 lines) ---
Found RTP audio format 0
Found RTP audio format 101
Found audio description format PCMU for ID 0
Found audio description format telephone-event for ID 101
Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 172.31.1.34:32256
list_route: hop: <sip:420000@172.31.1.34:5060;user=phone>
set_destination: Parsing <sip:420000@172.31.1.34:5060;user=phone> for address/port to send to
set_destination: set destination to 172.31.1.34:5060
Transmitting (no NAT) to 172.31.1.34:5060:
ACK sip:420000@172.31.1.34:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK6a8b811b
Max-Forwards: 70
From: "Test2" <sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>;tag=a0a610ac
Contact: <sip:910000@172.30.1.206:5060>
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.8.8.0
Content-Length: 0


---
    -- SIP/ttk-0000019a answered SIP/201-00000199

<--- SIP read from UDP:172.31.1.34:5060 --->
OPTIONS sip:910000@172.30.1.206:5060 SIP/2.0
Via: SIP/2.0/UDP 172.31.1.34:5060;branch=z9hG4bK2b18475285c9e93483d26f914
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
From: <sip:420000@172.31.1.34>;tag=a0a610ac
To: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
CSeq: 1 OPTIONS
Accept: application/sdp
Max-Forwards: 70
Content-Length: 0

<------------->
--- (9 headers 0 lines) ---

<--- Transmitting (no NAT) to 172.31.1.34:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.31.1.34:5060;branch=z9hG4bK2b18475285c9e93483d26f914;received=172.31.1.34
From: <sip:420000@172.31.1.34>;tag=a0a610ac
To: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
CSeq: 1 OPTIONS
Server: Asterisk PBX 1.8.8.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:910000@172.30.1.206:5060>
Accept: application/sdp
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060' in 32000 ms (Method: OPTIONS)
set_destination: Parsing <sip:420000@172.31.1.34:5060;user=phone> for address/port to send to
set_destination: set destination to 172.31.1.34:5060
Reliably Transmitting (no NAT) to 172.31.1.34:5060:
BYE sip:420000@172.31.1.34:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK0b02d2ed
Max-Forwards: 70
From: "Test2" <sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>;tag=a0a610ac
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
CSeq: 103 BYE
User-Agent: Asterisk PBX 1.8.8.0
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


---
  == Spawn extension (office, 420000, 1) exited non-zero on 'SIP/201-00000199'

<--- SIP read from UDP:172.31.1.34:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.30.1.206:5060;branch=z9hG4bK0b02d2ed
Call-ID: 7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060
From: "Test2"<sip:910000@172.30.1.206>;tag=as0492590f
To: <sip:420000@172.31.1.34>;tag=a0a610ac
CSeq: 103 BYE
Content-Length: 0

<------------->
--- (7 headers 0 lines) ---
[Jan 10 00:47:54] NOTICE[60372]: chan_sip.c:20249 handle_response_peerpoke: Peer 'ttk' is now Lagged. (387477ms / 0ms)
Really destroying SIP dialog '7976cc6d244d16a46e20e07a656b8163@172.30.1.206:5060' Method: OPTIONS
ded писал(а):Во время звока через ttk когда Lagged. 111320ms
сделайте mtr 172.31.1.3 чтобы увидеть на каком узле трассы создаётся задержка.
Во время звонка задержки нет.
Снял MTR во время звонка с интервалом в 0.1 с:

Код: Выделить всё

                                       Packets               Pings
 Host                                Loss%   Snt   Last   Avg  Best  Wrst StDev
 1. 172.30.1.1                        0.0%   220    2.6   1.5   0.5  22.6   2.3
 2. 172.16.121.42                     0.0%   219    2.1   2.7   1.9  10.9   1.2
 3. 172.31.1.34                       0.0%   219    0.5   0.6   0.4   5.1   0.4

Код: Выделить всё

[Jan 11 21:48:01] NOTICE[38658]: chan_sip.c:20249 handle_response_peerpoke: Peer 'ttk' is now Lagged. (1786523ms / 0ms)
Out писал(а):а запись разговоров делаете?
Может сервак тупит заканчивая запись?
или проблемы с записью тарификации?
И сервак начинает тормозить жутко?
Не делаем. Нагрузка на сервак не увеличивается.
Vlad1983
Сообщения: 4251
Зарегистрирован: 09 авг 2011, 11:51

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение Vlad1983 »

Asterisk путает свои OPTIONS с присланными ttk, у них Call-ID совпадает с Call-ID звонка
и включает таймер разрушения диалога, хотя сам уже ответил на запрос не требующий подтверждения
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER: вырезка из заглавного поста проблемный
<--- SIP read from UDP:172.31.1.34:5060 --->
OPTIONS sip:910000@172.30.1.206:5060 SIP/2.0
Via: SIP/2.0/UDP 172.31.1.34:5060;branch=z9hG4bKe5360720dd30c8f48f88a8627
Call-ID: 4258239f5c36a01a4064fedf422d99f7@172.30.1.206:5060
From: <sip:322322@172.31.1.34>;tag=6e729246
To: "Test2"<sip:910000@172.30.1.206>;tag=as65eb7b9f
CSeq: 1 OPTIONS
Accept: application/sdp
Max-Forwards: 70
Content-Length: 0

<--- Transmitting (no NAT) to 172.31.1.34:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.31.1.34:5060;branch=z9hG4bKe5360720dd30c8f48f88a8627;received=172.31.1.34
From: <sip:322322@172.31.1.34>;tag=6e729246
To: "Test2"<sip:910000@172.30.1.206>;tag=as65eb7b9f
Call-ID: 4258239f5c36a01a4064fedf422d99f7@172.30.1.206:5060
CSeq: 1 OPTIONS
Server: Asterisk PBX 1.8.8.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:910000@172.30.1.206:5060>
Accept: application/sdp
Content-Length: 0



Scheduling destruction of SIP dialog '4258239f5c36a01a4064fedf422d99f7@172.30.1.206:5060' in 6400 ms (Method: OPTIONS)

[Dec 27 00:48:26] NOTICE[60372]: chan_sip.c:20249 handle_response_peerpoke: Peer 'ttk2' is now Lagged. (4539ms / 2000ms)
ЛС: @rostel
drTr0jan
Сообщения: 20
Зарегистрирован: 14 мар 2011, 16:51
Откуда: Хабаровск, РФ
Контактная информация:

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение drTr0jan »

Vlad1983
10 часов анализа этой мысли ни к чему не привели. Почему он путает? Может ли Call-ID совпадать? Непонятно.
Таймер разрушения диалога включается только в тот момент, как я положил трубку. А если трубку кладёт собеседник, то таймер разрушения диалога включается только после BYE/OK.

Изучал RFC на предмет назначения Call-ID для OPTIONS - ничего дельного не нашёл.

Что же делать, куда копать?

Да, по поводу транка с авторизацией по логину и паролю. Я тут Sfinxу немного неправильную инфу сказал. Такая проблема там тоже возникает, но при разговорах длительностью более 5 минут (первый OPTIONS от оператора прилетает через 5 минут в том случае).
Vlad1983
Сообщения: 4251
Зарегистрирован: 09 авг 2011, 11:51

Re: Увеличивается задержка (Peer is Lagged) после звонка.

Сообщение Vlad1983 »

попробовать скомпилить 1.8-current
ЛС: @rostel
Ответить
© 2008 — 2024 Asterisk.ru
Digium, Asterisk and AsteriskNOW are registered trademarks of Digium, Inc.
Design and development by PostMet-Netzwerk GmbH