Переодически пропадает регистрация
Астериск не может распознать ответ SIP OK???
|
Откуда: С-Пб
Сообщений: 31
|
Переодически пропадает регистрация
Всем добрый день!
Такая проблема: имеются 3 транка к Телфину, которые периодически отваливаются. Обычно отваливается либо идин транк (3-й), либо одновременно 1-й и 2-й. Другие комбинации встречаются реже. Отваливаются 2-5 раз в день на 2-5 минут.
Настройки транков практически идентичны:
[telf_XXXXXXXX]
username=XXXXXXXX
type=peer
secret=XXXXXXXX
promiscredir=yes
port=5068
insecure=port,invite
host=213.170.81.130
fromuser=XXXXXXXX
dtmfmode=rfc2833
canreinvite=no
context=from-trunk-sip-telf_XXXXXXXX
Незначительные отличия были внесены в процессе эксериментов и заключались в установке canreinvite=yes и qualify=yes в одном из транков, но к видимым изменениям не приводили.
АТС находится за NATом, в general.conf nat=yes.
Теперь самое интересное, в логах астериска происходит следующая последовательность:
1) У Астериска заканчивается время регистрации и он отправляет запрос на регистрацию
2) Приходит ответ 200 ОК
3) Астериск его почему-то игнорирует и спустя пару секунд отправляет повторно это же сообщение (Retransmitting #1 (NAT))
4) Опять приходит ответ 200 ОК
Пункты 3-4 повторяются 6 раз вплоть до Retransmitting #6
5) Потом появляется сообщение Registration for 'XXXXXXXX@213.170.81.130' timed out, trying again
6) Далее следует новая попытка регистрации начиная с пункта 1.
После 3-8 попыток регистрации он таки распознаёт ответ и транк вновь продолжает работу.
На данный момент есть лог астериска включенный для IP адреса Телфина и лог tcpdump'a с порта UDP 5060. Сейчас также снимается полный лог астериска.
Подскажите, в каком направлении двигаться дальше? Из-за чего может возникать такая проблема?
|
|
Откуда: SPb
Сообщений: 1307
|
Re: Переодически пропадает регистрация
nat ставится для пиров, которые обращаются к астериску. Настройте нормально nat на фаерволе и всё будет работать.
|
|
Откуда: С-Пб
Сообщений: 31
|
Re: Переодически пропадает регистрация
Полный конусный нат включить, что-ли? Или просто порты для проброса явно указать? Но чем это поможет, если пакет не просто доходит до этой машины, но и обрабатывается астериском? Да и раньше через этот же маршрутизатор без проблем работал астериск 1.2
Добавление:
Во время тестирования и снятия логов порт UDP 5060 был явно проброшен на АТС, но проблема появлялась всё равно. Пробросить ещё и 10000-20000?
Добавление 2:
Сегодня отвалились все 3 транка одновременно. Рестартанул из консоли астер - тут же поднялись опять..
Вообще такой вопрос: что означает "really destroying sip dialog" или "Scheduling destruction sip dialog"? Нагуглил только советы не обращать внимание/выключить отладчик, остальное - куски выложенных логов с этим фрагментом. Я не являюсь специалистом в VoIP, но логика подсказывает, что обмен сигнализацией происходит в пределах сеансов с уникальным номером, но сеанс этот по времени не бесконечен и эти сообщения показывают, что сеанс этот закрывается. Вот только понять не могу, scheduling - вроде как плановое закрытие сеанса, а really destroying что означает? Что сеанс действительно завершён? Как пользоваться этой информацией при отладке?
|
|
Откуда: SPb
Сообщений: 1307
|
Re: Переодически пропадает регистрация
не путайте порт форвардинг с натом. Просто откройте порты и просто занатьте хост. порты 5060 10000:20000 UDP. Обманывать железки о том, с каких и на каких портах они должны работать - очень нехорошо.
|
|
Откуда: С-Пб
Сообщений: 31
|
Re: Переодически пропадает регистрация
zzuz: Просто откройте порты и просто занатьте хост. порты 5060 10000:20000 UDP
> From: PBX To: Any UDP 5060, UDP 10000-20000 Allow Translation (NAT)
Я правильно вас понял? Так было изначально и так есть сейчас. Но в процессе экспериментов на пол дня появлялось такое правило:
> From: Any To: Firewall UDP 5060 Allow Map: PBX
Правило это прибил через пол дня, но за это время ситуация никак не менялась, всё равно 2 раза была потеря регистрации.
|
|
Откуда: SPb
Сообщений: 1307
|
Re: Переодически пропадает регистрация
Поставьте таймаут поменьше.
|
|
Сообщений: 76
|
Re: Переодически пропадает регистрация
Schastliviy, включите отладку на астериске(sip set debug), и если как вы говорите там появятся на запросы о регистрации ответ с кодом 200, то запрос и ответ сюда.
Что бы не следить постоянно за консолью в файле logger.conf раскоментируйте verbose => verbose и перезапустите logger и отладка сип будет складываться в /var/log/verbose
Если как вы пишите астериск не распознаёт ответ 200 наверное на то есть причина.
|
|
Откуда: С-Пб
Сообщений: 31
|
Re: Переодически пропадает регистрация
zzuz, таймаут какой именно? Перерегистрации? Ответ от сервера на запрос приходит в ту же секунду, задержек в логах нету.
kasper, логи прямо так и выложить рулоном (кончено же кусок в несколько минут), или как-то можно оцивилить? У меня сейчас есть готовый лог full снятый с параметрами sip set debug ip {адрес сип сервера}, он подойдёт?
|
|
Сообщений: 76
|
Re: Переодически пропадает регистрация
Schastliviy: kasper, логи прямо так и выложить рулоном
Как я писал выше только запрос и ответ.
Всмысле поиском(а проще грепом) найти и скопировать кусочки из вашего full файла
|
|
Откуда: С-Пб
Сообщений: 31
|
Re: Переодически пропадает регистрация
Все лишние строчки убрал, оставил только пакеты.
Последний нормальный обмен перед глюком:
[Nov 12 16:23:11] VERBOSE[1366] chan_sip.c: REGISTER 12 headers, 0 lines
[Nov 12 16:23:11] VERBOSE[1366] chan_sip.c: Reliably Transmitting (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK034ac244;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as2454cdee
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 149 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:23:11] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK034ac244;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as2454cdee
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-qavs4c00000a9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 149 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
Через минуту:
[Nov 12 16:23:56] VERBOSE[1366] chan_sip.c: REGISTER 12 headers, 0 lines
[Nov 12 16:23:56] VERBOSE[1366] chan_sip.c: Reliably Transmitting (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK63ad6144;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:23:56] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK63ad6144;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-dp0vh900000c9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
<------------->
[Nov 12 16:23:57] VERBOSE[1366] chan_sip.c: --- (10 headers 0 lines) ---
[Nov 12 16:23:57] VERBOSE[1366] chan_sip.c: Retransmitting #1 (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK63ad6144;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:23:57] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK63ad6144;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-dp0vh900000c9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
Так продолжается вплоть до Retransmitting #6, после чего идёт тайм-аут и всё начинает новый Attempt
<------------->
[Nov 12 16:24:08] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:24:12] VERBOSE[1366] chan_sip.c: Retransmitting #6 (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK63ad6144;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:24:12] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK63ad6144;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as6038e9b4
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-dp0vh900000c9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 150 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
<------------->
[Nov 12 16:24:12] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:24:16] NOTICE[1366] chan_sip.c: -- Registration for '000XXXX3@213.170.81.130' timed out, trying again (Attempt #1)
[Nov 12 16:24:16] VERBOSE[1366] chan_sip.c: REGISTER 12 headers, 0 lines
[Nov 12 16:24:16] VERBOSE[1366] chan_sip.c: Reliably Transmitting (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK7106c9c2;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as390872f5
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 151 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:24:16] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK7106c9c2;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as390872f5
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-ehk22a00000e9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 151 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
<------------->
[Nov 12 16:24:16] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:24:17] VERBOSE[1366] chan_sip.c: Retransmitting #1 (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK7106c9c2;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as390872f5
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 151 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
После нескольких неудачных минут, при Attempt #3 и Retransmitting #5 таки происходит регистрация и всё приходит в норму, последующая регистрация идёт уже через 45 секунд
<------------->
[Nov 12 16:25:00] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:25:04] VERBOSE[1366] chan_sip.c: Retransmitting #4 (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK35696e64;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as21856132
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 153 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:25:04] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK35696e64;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as21856132
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-81kehk30000i9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 153 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
<------------->
[Nov 12 16:25:04] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:25:12] VERBOSE[1366] chan_sip.c: Retransmitting #5 (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK35696e64;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as21856132
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 153 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:25:12] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK35696e64;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as21856132
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-81kehk30000i9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 153 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
<------------->
[Nov 12 16:25:49] VERBOSE[1366] chan_sip.c: --- (7 headers 0 lines) ---
[Nov 12 16:25:57] NOTICE[1366] chan_sip.c: -- Re-registration for 000XXXX3@213.170.81.130
[Nov 12 16:25:57] VERBOSE[1366] chan_sip.c: REGISTER 12 headers, 0 lines
[Nov 12 16:25:57] VERBOSE[1366] chan_sip.c: Reliably Transmitting (NAT) to 213.170.81.130:5068:
REGISTER sip:213.170.81.130:5068 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.4:5060;branch=z9hG4bK18fbfcf1;rport
Max-Forwards: 70
From: <sip:000XXXX3@213.170.81.130>;tag=as46d7c84e
To: <sip:000XXXX3@213.170.81.130>
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 154 REGISTER
User-Agent: ORG_PBX
Authorization: Digest username="000XXXX3", realm="sip.telphin.com", algorithm=MD5, uri="sip:213.170.81.130:5068", nonce="4cdd3e29000161f2f0ba4e2dc39279d4c0a47f3e761875d9", response="962253527c9312099007392f85a6d8bb"
Expires: 120
Contact: <sip:000XXXX3@10.0.0.4>
Content-Length: 0
---
[Nov 12 16:25:57] VERBOSE[1366] chan_sip.c:
<--- SIP read from UDP:213.170.81.130:5068 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.4:5060;received=123.123.123.123;branch=z9hG4bK18fbfcf1;rport=5060
From: <sip:000XXXX3@213.170.81.130>;tag=as46d7c84e
To: <sip:000XXXX3@213.170.81.130>;tag=aprqn3fi411-tqv82b00000k9
Call-ID: 5edeb70740d80bd36fc6bd667c768273@127.0.1.1
CSeq: 154 REGISTER
Contact: <sip:000XXXX3@10.0.0.4>;expires=60
Пакеты Retransmitting #4 и Retransmitting #5 смотрел в wireshark - разделы SIP абсолютно идентичны
Добавление:
Два других транка с этой же атс на этот же сервер телфина в то же самое время регистрировались нормально.
Добавление 2:
Версия Астериск 1.6.2.7 |
|
