Периодические разрывы связи

Cvieri · Сообщение **Cvieri** » 31 авг 2011, 16:16

Cisco 7940 телефон -> Астериск -> Portech GSM шлюз

Периодически обрывается связь, решил половить tcpdump-ом.

[b]На шлюзе:[/b]

17:38:27.109201 IP 192.168.0.103.sip > 192.168.0.253.5070: SIP, length: 363
E`..E...@......g.........s.9BYE sip:101@192.168.0.253:5070 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK6b22d4b1;rport
From: "ххххххх" <sip:ххххххх@192.168.0.103>;tag=as75005238
To: <sip:ххххххх@192.168.0.253:5070>;tag=72a38fe5
Call-ID: 525584620ffdc79902716a5a2d987c37@192.168.0.103
CSeq: 103 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

17:38:27.309258 IP 192.168.0.103.sip > 192.168.0.253.5070: SIP, length: 363
E`..E...@......g.........s.9BYE sip:101@192.168.0.253:5070 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK6b22d4b1;rport
From: "ххххххх" <sip:ххххххх@192.168.0.103>;tag=as75005238
To: <sip:ххххххх@192.168.0.253:5070>;tag=72a38fe5
Call-ID: 525584620ffdc79902716a5a2d987c37@192.168.0.103
CSeq: 103 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0
[b]
На телефоне:[/b]

17:38:27.109017 IP 192.168.0.103.sip > 192.168.0.70.sip-tls: SIP, length: 407
E`...$..@.q....g...F........BYE sip:414@192.168.0.70:5061;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK38ede1eb;rport
From: "ххххххх" <sip:ххххххх@192.168.0.103>;tag=as6e3d7192
To: <sip:414@192.168.0.70:5061;transport=udp>;tag=001647bc38684bc40a6775e8-33c421f0
Call-ID: 56381d432c278ba0772f0f9f47ecd2d9@192.168.0.103
CSeq: 103 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

17:38:27.197466 IP 192.168.0.70.sip-tls > 192.168.0.103.sip: SIP, length: 499
E`..Zi..@......F...g........SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK38ede1eb;rport
From: "ххххххх" <sip:ххххххх@192.168.0.103>;tag=as6e3d7192
To: <sip:414@192.168.0.70:5061;transport=udp>;tag=001647bc38684bc40a6775e8-33c421f0
Call-ID: 56381d432c278ba0772f0f9f47ecd2d9@192.168.0.103
Date: Mon, 29 Aug 2011 14:38:43 GMT
CSeq: 103 BYE
Server: Cisco-CP7940G/8.0
Content-Length: 0
RTP-RxStat: Dur=45,Pkt=1214,Oct=194240,LatePkt=0,LostPkt=0,AvgJit=1
RTP-TxStat: Dur=45,Pkt=2255,Oct=360800

Другой звонок, дебаг последних секунд:

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

[2011-08-31 12:19:59] VERBOSE[30634] logger.c:     -- Remote UNIX connection disconnected
[2011-08-31 12:19:59] VERBOSE[30636] logger.c:     -- Remote UNIX connection disconnected
[2011-08-31 12:19:59] VERBOSE[2812] logger.c:     -- Remote UNIX connection
[2011-08-31 12:19:59] VERBOSE[2821] logger.c: Really destroying SIP dialog '69dfa8375a9ce92e7390f97b32eb3583@192.168.0.253' Method: REGISTER
[2011-08-31 12:20:00] VERBOSE[29453] logger.c: Scheduling destruction of SIP dialog '005800b92297750a7ad79ad3135e1598@192.168.0.103' in 6400 ms (Method: INV$
[2011-08-31 12:20:00] DEBUG[29453] chan_sip.c: Strict routing enforced for session 005800b92297750a7ad79ad3135e1598@192.168.0.103
[2011-08-31 12:20:00] VERBOSE[29453] logger.c: set_destination: Parsing <sip:1024@192.168.0.253:5070> for address/port to send to
[2011-08-31 12:20:00] VERBOSE[29453] logger.c: set_destination: set destination to 192.168.0.253, port 5070
[2011-08-31 12:20:00] VERBOSE[29453] logger.c: Reliably Transmitting (no NAT) to 192.168.0.253:5070:
BYE sip:1024@192.168.0.253:5070 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK663e8de3;rport
From: "CID:414-0503668ххх=1233113" <sip:Unknown@192.168.0.103>;tag=as637d6d5b
To: <sip:0503668ххх@192.168.0.253:5070>;tag=5294a8ff
Call-ID: 005800b92297750a7ad79ad3135e1598@192.168.0.103
CSeq: 103 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

[2011-08-31 12:20:00] VERBOSE[29453] logger.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/414-086c5300' in macro 'dialout-tru$
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/414-086c5300'
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/414-086c5300", "hangupcall|") in new stack
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/414-086c5300", "vw") in new stack
[2011-08-31 12:20:00] DEBUG[29453] app_macro.c: Executed application: ResetCDR
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:2] NoCDR("SIP/414-086c5300", "") in new stack
[2011-08-31 12:20:00] DEBUG[29453] app_macro.c: Executed application: NoCDR
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:3] GotoIf("SIP/414-086c5300", "1?skiprg") in new stack
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Goto (macro-hangupcall,s,6)
[2011-08-31 12:20:00] DEBUG[29453] app_macro.c: Executed application: GotoIf
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:6] GotoIf("SIP/414-086c5300", "1?skipblkvm") in new stack
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Goto (macro-hangupcall,s,9)
[2011-08-31 12:20:00] DEBUG[29453] app_macro.c: Executed application: GotoIf
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:9] GotoIf("SIP/414-086c5300", "1?theend") in new stack
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Goto (macro-hangupcall,s,11)
[2011-08-31 12:20:00] DEBUG[29453] app_macro.c: Executed application: GotoIf
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:     -- Executing [s@macro-hangupcall:11] Hangup("SIP/414-086c5300", "") in new stack
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/414-086c5300' in macro 'hangupcall'
[2011-08-31 12:20:00] VERBOSE[29453] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/414-086c5300'
[2011-08-31 12:20:00] DEBUG[29453] chan_sip.c: Call to peer '414' removed from call limit 2
[2011-08-31 12:20:00] VERBOSE[29350] logger.c:   == Manager 'easycall' logged off from 192.168.0.103
[2011-08-31 12:20:00] VERBOSE[30638] logger.c:     -- Remote UNIX connection disconnected
[2011-08-31 12:20:00] VERBOSE[2821] logger.c:
<--- SIP read from 192.168.0.253:5070 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.103:5060;rport=5060;received=192.168.0.103;branch=z9hG4bK663e8de3
From: "CID:414-0503668ххх=1233113" <sip:Unknown@192.168.0.103>;tag=as637d6d5b
To: <sip:0503668ххх@192.168.0.253:5070>;tag=5294a8ff
Call-ID: 005800b92297750a7ad79ad3135e1598@192.168.0.103
CSeq: 103 BYE
Content-Length: 0

<------------->
[2011-08-31 12:20:00] VERBOSE[2821] logger.c: --- (7 headers 0 lines) ---
[2011-08-31 12:20:00] VERBOSE[2821] logger.c: Really destroying SIP dialog '005800b92297750a7ad79ad3135e1598@192.168.0.103' Method: INVITE
[2011-08-31 12:20:00] VERBOSE[2821] logger.c:

Судя по логам это Астер инициирует разрыв, не так ли? Если так, то какого?

ded · Сообщение **ded** » 31 авг 2011, 17:27

Не так.
BYE прислал телефон - sip:414@192.168.0.70:5061
А Астериск 192.168.0.103 лишь передал его на шлюз 192.168.0.253

Cvieri · Сообщение **Cvieri** » 31 авг 2011, 19:02

Ок, если так, то с чего так? Опреаторы не сбрасывают, а говорят что связь сама рвётся. Может прошивка?

И во втором случае кто BYE послал? Шлюз.

Vlad1983 · Сообщение **Vlad1983** » 31 авг 2011, 19:29

Если рвет с примерно одинаковой периодичностью гляньте в general sip.conf не стоит ли session-timers=...

если стоит удалите, либо попробуйте поставьте значение accept или refuse

и ещё такое бывает на виртуалках по абсолютно необъяснимым причинам
так что если на виртуалке лучше переехать

ded · Сообщение **ded** » 31 авг 2011, 19:36

Cvieri писал(а):И во втором случае кто BYE послал? Шлюз.

Да нет, шлюз лишь ответил ОК на BYE
<--- SIP read from 192.168.0.253:5070 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.103:5060;rport=5060;received=192.168.0.103;branch=z9hG4bK663e8de3
From: "CID:414-0503668ххх=1233113" <sip:Unknown@192.168.0.103>;tag=as637d6d5b
To: <sip:0503668ххх@192.168.0.253:5070>;tag=5294a8ff
Call-ID: 005800b92297750a7ad79ad3135e1598@192.168.0.103
CSeq: 103 BYE

Зацепите весь диалог в Wireshark и проанализируйте.

- Это ж-ж-ж - неспроста! © Винни-Пух.

Cvieri · Сообщение **Cvieri** » 31 авг 2011, 19:45

Спасибо, попробую шарком половить.

Нет, определенной периодичности нет, просто бывает иногда.

Vlad1983 · Сообщение **Vlad1983** » 31 авг 2011, 20:57

настораживает вот что

[2011-08-31 12:20:00] VERBOSE[29453] logger.c: Scheduling destruction of SIP dialog '005800b92297750a7ad79ad3135e1598@192.168.0.103' in 6400 ms (Method: INV$

похоже всё-так какой-то таймер срабатывает

кроме сигнальника надоглянуть может RTP перестает в какую-то сторону бежать и срабатывает разъединение мертвых каналов
попробовать увеличить до таких значений
rtptimeout=90
rtpholdtimeout=300

и глянуть в инвайтах астериска не стоит ли в SDP a=silenceSupp:off - - - -
если есть надо использовать тайминг, чтоб не было

kasper · Сообщение **kasper** » 01 сен 2011, 10:54

Vlad1983 писал(а):настораживает вот что
[2011-08-31 12:20:00] VERBOSE[29453] logger.c: Scheduling destruction of SIP dialog '005800b92297750a7ad79ad3135e1598@192.168.0.103' in 6400 ms (Method: INV$

похоже всё-так какой-то таймер срабатывает

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

ded писал(а):Не так.
BYE прислал телефон - sip:414@192.168.0.70:5061
А Астериск 192.168.0.103 лишь передал его на шлюз 192.168.0.253

ded, как вы это определили? Что то не вижу нигде bye от телефона только это

17:38:27.109017 IP 192.168.0.103.sip > 192.168.0.70.sip-tls: SIP, length: 407
E`...$..@.q....g...F........BYE sip:414@192.168.0.70:5061;transport=udp SIP/2.0

Т.е. отправка bye на телефон

ded · Сообщение **ded** » 01 сен 2011, 12:01

Там tcpdump выцеплено два разных события, а не одно.
17:38:27.309258 IP 192.168.0.103.sip > 192.168.0.253.5070: SIP, length: 363
E`..E...@......g.........s.9BYE sip:101@192.168.0.253:5070 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.103:5060;branch=z9hG4bK6b22d4b1;rport
From: "ххххххх" <sip:ххххххх@192.168.0.103>;tag=as75005238
To: <sip:ххххххх@192.168.0.253:5070>;tag=72a38fe5
Call-ID: 525584620ffdc79902716a5a2d987c37@192.168.0.103
CSeq: 103 BYE

Астериск (192.168.0.103) передал на шлюз (192.168.0.253) CSeq: 103 BYE.
Этот BYE - передан с телефона, Астериск его не выдумал.

Следующий дамп на телефоне описывает другой вызов, Астериск 192.168.0.103.sip посылает BYE на телефон > 192.168.0.70.sip-tls (и телефон ответил ОК) и это тоже послала BYE другая сторона.
Думаю, что если ловить разные плечи разных звонков, то понять трудно. Думаю, что топикстартер мог бы хоть накидать ИП схему звонков в виде
Шлюз (192.168.0.253) ==> Астериск (192.168.0.103) ==> ИП телефон (192.168.0.70)
а то морщить лоб в логе разбирать who is who - тоскливо.

Vlad1983 · Сообщение **Vlad1983** » 01 сен 2011, 12:07

и вообще лучше дамп куда-нить выкладывать.
варшарком самому проще анализировать чем в кусках разбираться.
начинают IP закрывать, номера.
скрытные какие.
кому вы нафиг нужны с вашими секретами.
IP жертв не по форумам ищут, а тупо сканять всё сети.

Периодические разрывы связи

Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи

Re: Периодические разрывы связи