Opened 10 days ago

Last modified 6 days ago

#443 reopened баг

Неудачный вызов со стороны окончания FxO

Reported by: san Owned by: alx
Priority: средний Milestone: 1 очередь
Component: any Keywords:
Cc:

Description

Из окончания FXO VE-01 с помощью функции "Вызывать URI" пользователь пытается вызывать абонента 4390 (который "живёт" в аппаратуре Eltex SMG3016). Однако, со слов пользователя, в момент попытки вызова "происходит разъединение".
Я попросил пользователя прислать дамп обмена сип сообщениями, однако в дампе следов вызова я не обнаружил.
Лог с платы VE-01 в момент происшествия пользователь обещал прислать позже.
10.62.113.132 - VE-01
10.62.40.24 - Eltex SMG3016
Версия прошивки VE-01 - 88.
Дамп Sip и конфиг платы прилагаю.

Attachments (2)

FXO на 4390.txt​ (11.2 KB ) - added by san 10 days ago.
config-ИА_МОГЭС_12__МС04_6-24-09-2024.xml​ (34.4 KB ) - added by san 10 days ago.

Download all attachments as: .zip

Change History (20)

by san, 10 days ago

Attachment: FXO на 4390.txt​ added

by san, 10 days ago

Attachment: config-ИА_МОГЭС_12__МС04_6-24-09-2024.xml​ added

comment:1 by san, 10 days ago

Milestone: 2 очередь → 1 очередь

comment:2 by san, 10 days ago

Конфиг платы пользователь прислал внутри конфига блока - плата VE-01 в слоте 3.

comment:3 by san, 10 days ago

Канальное окончание FXO о котором речь, находится в канале 31

comment:4 by san, 9 days ago

Пользователь прислал лог

Sep 26 10:56:41 syslogd started: BusyBox v1.18.5

Sep 26 10:56:46 sip_ua[391]: fxo.cpp:272: ts 30: dialing 4390

Sep 26 10:56:46 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out()  -> sip:4390@10.62.113.132...

Sep 26 10:56:46 sip_ua[473]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK1051143813'

Sep 26 10:56:46 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!): cid=903, did=0, tid=8

73, rid=0, sid=0, nid=0

Sep 26 10:56:46 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Calling: Call disconnected, ts=30, flags=0000, data=903

Sep 26 10:56:46 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 26 10:56:46 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 26 10:56:46 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Drop Line: CAS event, ts=30, flags=0000, data=5

Sep 26 10:56:53 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Idle: CAS event, ts=30, flags=0000, data=13

Sep 26 10:56:54 sip_ua[391]: fxo.cpp:272: ts 30: dialing 4390

Sep 26 10:56:54 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out()  -> sip:4390@10.62.113.132...

Sep 26 10:56:54 sip_ua[473]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK1923655612'

Sep 26 10:56:54 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!): cid=904, did=0, tid=8

74, rid=0, sid=0, nid=0

Sep 26 10:56:54 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Calling: Call disconnected, ts=30, flags=0000, data=904

Sep 26 10:56:54 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 26 10:56:54 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 26 10:56:54 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Drop Line: CAS event, ts=30, flags=0000, data=5

Sep 26 10:57:05 syslogd exiting

comment:5 by san, 9 days ago

p.s. дамп и лог записаны пользователем в двух разных экспериментах, со слов пользователя и в обоих экспериментах он делал одно и тоже и результат получил одинаковый.

comment:6 by alx, 9 days ago

На начальной странице проекта есть полезный текст о том, как сделать хороший баг-рипорт. Рекомендую перечитать. Если очень коротко, то хороший баг-рипорт состоит из трех основных частей: что делали, что ожидали получить, и что получили вместо ожидаемого. К сожалению, в описании тикета есть только два из трех основных компонентов - что делали и что получили в результате. Так как тип тикета установлен в "баг", я догадываюсь, что ожидали какой-то другой результат. Однако этого в описании тикета нет, поэтому я так и не понял, в чем же по твоему мнению заключается баг. Изложенного в описании тикета для такого вывода по-моему недостаточно.

Уточни, пожалуйста, что (и почему) ты ожидал получить вместо фактически полученного результата.

comment:7 by alx, 9 days ago

Уточни, пожалуйста, какое состояние имеет сигнальный канал PRI (канал 64).

in reply to:  7 ; comment:8 by alx, 9 days ago

Ой, случайно вместо "Редактировать" нажал "Ответить. :)

Уточни, пожалуйста, какое состояние имел сигнальный канал PRI (канал 64) на момент эксперимента. И были ли в транке свободные разговорные каналы.

Last edited 9 days ago by alx (previous) (diff)

in reply to:  8 comment:9 by alx, 9 days ago

Replying to alx:

Уточни, пожалуйста, какое состояние имел сигнальный канал PRI (канал 64) на момент эксперимента. И были ли в транке свободные разговорные каналы.

Вопрос снимается. Я не заметил сразу, что там есть маршрут "МС04->IA_SMG3016_1", направляющий вызов 4390@10.62.113.132 на 4390@10.62.113.132. Я думал, что вызов идет в PRI...

Last edited 9 days ago by alx (previous) (diff)

comment:10 by san, 9 days ago

Уточни, пожалуйста, что (и почему) ты ожидал получить вместо фактически полученного результата

Я надеялся что это понятно из названия тикета, пользователь ожидал получить "удачный" вызов :)

Пользователь ожидал что FXO произведёт вызов на 4390@10.62.40.24 и далее состоится разговор.

in reply to:  10 comment:11 by alx, 9 days ago

Resolution: → invalid
Status: new → closed

Replying to san:

Я надеялся что это понятно из названия тикета, пользователь ожидал получить "удачный" вызов :)

Если бы тикет создал пользователь, то и разговор я, наверное, строил бы по-другому, и вопросы задавал немного другие. Но автор тикета - мой коллега, высококлассный специалист в области связи и коммуникаций. Поэтому я ожидал от него более глубокого анализа ситуации и более точных формулировок.

Приведу немного утрированный пример. Элис звонит Бобу, ожидая, что разговор состоится. Но Боб в это время разговаривает с Тэдом, и поэтому Элис, вопреки своим ожиданиям, получает "Занято". Означает ли это, что аппаратуры связи Элис сломана, ведь ожидания Элис не оправдались? :) Очевидно, что нет - бывают случаи, когда разговор не может состояться по объективным причинам, не зависящим от аппаратуры Элис...

Вот поэтому я и ожидал, что если ты считаешь, что в плате VE-01 имеет место баг (тип тикета - "баг"), у тебя есть для этого более веские основания чем не оправдавшееся ожидание пользователя-домохозяйки. :)

Пользователь ожидал что FXO произведёт вызов на 4390@10.62.40.24 и далее состоится разговор.

Ну, при такой формулировке я могу только закрыть тикет как invalid. :)

Как мы видим в приложенном к тикету файле, плата VE-01 с адреса 10.62.113.132 инициирует соединение с хостом 10.62.40.24 (Eltex SMG3016), отправляя пакет SYN. Однако в ответ на него от Eltex SMG3016 вместо ожидаемого SYN+ACK приходит RESET (то есть отказ от установки соединения). Вывод - проблема находится на стороне Eltex SMG3016. Оснований предполагать наличие бага в плате VE-01 я не вижу.

comment:12 by san, 9 days ago

Если бы тикет создал пользователь

В данном случае я просто создал тикет за пользователя,передав сюда его слова и файлы, мотороллер не мой.

отправляя пакет SYN. Однако в ответ на него от Eltex SMG3016 вместо ожидаемого SYN+ACK приходит RESET

А можешь процитировать эти сообщения из файла?

in reply to:  12 comment:13 by alx, 9 days ago

Replying to san:

В данном случае я просто создал тикет за пользователя,передав сюда его слова и файлы, мотороллер не мой.

В поле "Сообщил" (Reported by) тикета я вижу твой логин, поэтому и все прочие слова (тип тикета, описание тикета) считаю исходящими от тебя. Если ты при этом действуешь не по собственной инициативе, а по чьей-то еще просьбе - это твои личные обстоятальства. В описании тикета об этом не сказано...

отправляя пакет SYN. Однако в ответ на него от Eltex SMG3016 вместо ожидаемого SYN+ACK приходит RESET

А можешь процитировать эти сообщения из файла?

Конечно. Это два самых первых сообщения:

08:48:03.593886 IP (tos 0x0, ttl 64, id 38094, offset 0, flags [DF], proto TCP (6), length 52)
    10.62.113.132.56252 > 10.62.40.24.5060: Flags [S], cksum 0x3cda (correct), seq 1524966126, win 5840, options [mss 1460,nop,nop,sackOK,nop,wscale 2], length 0
08:48:03.594621 IP (tos 0x0, ttl 61, id 58085, offset 0, flags [DF], proto TCP (6), length 40)
    10.62.40.24.5060 > 10.62.113.132.56252: Flags [R.], cksum 0x8d56 (correct), seq 548136545, ack 1524966127, win 0, length 0
Last edited 9 days ago by alx (previous) (diff)

comment:14 by san, 9 days ago

Ага, спасибо, не разглядел tcp пакеты с флагами.
Подозреваю, что проблема пользователя в том, что в этом окончании FXO настроен транспорт TCP, с которым Eltex не хочет работать. В остальных окончаниях установлен транспорт UDP.

В поле "Сообщил" (Reported by) тикета я вижу твой логин

Я посмотрел конфиг, не увидел там ничего плохого, попробовал сделать вызов на своей плате - всё работает, глянул дамп пользователя(не заметив там TCP пакетов)и не увидел там вызова.
Дальше я просто транслировал то что сообщил пользователь в тикет, оставляя формулировки, специально, чтобы случайно не переврать слова пользователя.
Насколько я вижу, ты понял описание тикета правильно и нашёл проблему. Кажется, всё хорошо)

Last edited 9 days ago by san (previous) (diff)

in reply to:  14 comment:15 by alx, 9 days ago

Replying to san:

Подозреваю, что проблема пользователя в том, что в этом окончании FXO настроен транспорт TCP, с которым Eltex не хочет работать. В остальных окончаниях установлен транспорт UDP.

Вообще-то установка в настройках канального окончания транспорта UDP не гарантирует, что сообщение будет отправляться по UDP, и поэтому большого значения не имеет. UAS в любом случае должен быть готов принять сообщение по TCP (если он принимает их по UDP). Тот факт, что Eltex SMG3016 отказывается установить соединение TCP, говорит о том, что либо в нем что- неправильно настроено (например забыли открыть TCP порт 5060 в файрволе), либо либо это баг в Eltex SMG3016.

В поле "Сообщил" (Reported by) тикета я вижу твой логин

Я посмотрел конфиг, не увидел там ничего плохого, попробовал сделать вызов на своей плате - всё работает, глянул дамп пользователя(не заметив там TCP пакетов)и не увидел там вызова.

А, в описании тикета написано "следов вызова я не обнаружил" - наверное это должно было навести меня на мысль о том, что ты случайно их проглядел. Но я сам сначала проглядел маршрут SIP, перенаправляющий вызов, и поэтому думал, что вызов должен идти в PRI (поэтому и задавал вопросы о нем, и исходники PRI изучал, и вообще потратил на этот ложный след много времени). Я еще тогда подумал: "конечно не обнаружил, ведь пользователь не указал -i lo, поэтому tcpdump прицепился к внешнему интерфейсу, и сообщения-то чисто внутренние"... А когда обнаружил маршрут, то уже забыл про не найденные тобой следы... :)

Было бы лучше сразу подробнее описывать, что ожидалось. Например как-то так: ожидалось, что окончание FXO отправит INVITE на URI 4390@10.62.113.132, который в прокси маршрутом SIP будет изменен на 4390@10.62.40.24 и выйдет из платы во внешнюю сеть, но в дампе этого сообщения не обнаружено...

Насколько я вижу, ты понял описание тикета правильно и нашёл проблемму. Кажется, всё хорошо)

Так я же нашел совсем не ту проблему, которая заявлялась тобой в тикете! В тикете заявлялось, что в VE-01 обнаружен баг (то есть что VE-01 ведет себя неправильно). Я свидетельств наличия бага в VE-01 не нашел.

Last edited 9 days ago by alx (previous) (diff)

comment:16 by san, 8 days ago

Было бы лучше сразу подробнее описывать, что ожидалось. Например как-то так: ожидалось, что окончание FXO отправит INVITE на URI 4390@10.62.113.132, который в прокси маршрутом SIP будет изменен на 4390@10.62.40.24 и выйдет из платы во внешнюю сеть, но в дампе этого сообщения не обнаружено...

Согласен. От пользователя мне досталась информация что "вызов не успешен". А прочитав конфиг и проведя эксперимент, я забыл уточнить, т.к. для меня это было уже очевидно)

Так я же нашел совсем не ту проблему, которая заявлялась тобой в тикете! В тикете заявлялось, что в VE-01 обнаружен баг (то есть что VE-01 ведет себя неправильно). Я свидетельств наличия бага в VE-01 не нашел.

А тут всё правильно, пользователь считал что это баг в плате VE-01(ну то есть у него всё работало когда-то, а теперь перестало и связал он это с обновлением платы), а ты аргументированно показал что вины платы VE-01 тут нет.

comment:17 by san, 8 days ago

Resolution: invalid
Status: closed → reopened

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

Пользователь установил в окончании транспорт UDP, вызов по прежнему "не проходит".
Прикладываю новый дамп Sip и лог.

Sep 27 11:35:29 syslogd started: BusyBox v1.18.5

Sep 27 11:35:35 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=5

Sep 27 11:35:38 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=13

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=23, state=Idle: CAS event, ts=23, flags=0000, data=5

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=17, state=Idle: CAS event, ts=17, flags=0000, data=11

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=19, state=Idle: CAS event, ts=19, flags=0000, data=5

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=20, state=Idle: CAS event, ts=20, flags=0000, data=13

Sep 27 11:35:39 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=9

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=17, state=Idle: CAS event, ts=17, flags=0000, data=5

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=19, state=Idle: CAS event, ts=19, flags=0000, data=13

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=20, state=Idle: CAS event, ts=20, flags=0000, data=5

Sep 27 11:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=23, state=Idle: CAS event, ts=23, flags=0000, data=1

Sep 27 11:35:39 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=13

Sep 27 11:35:40 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=5

Sep 27 11:35:43 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=13

Sep 27 11:35:44 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=5

Sep 27 11:35:47 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=13

Sep 27 11:35:47 sip_ua[391]: fxo.cpp:272: ts 30: dialing 4390

Sep 27 11:35:47 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out()  -> sip:4390@10.62.113.132...

Sep 27 11:35:47 sip_ua[473]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK1148600048'

Sep 27 11:35:47 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!): cid=968, did=0, tid=1051, rid=0, sid=0,

nid=0

Sep 27 11:35:47 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Calling: Call disconnected, ts=30, flags=0000, data=968

Sep 27 11:35:47 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 27 11:35:47 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream

Sep 27 11:35:48 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Drop Line: CAS event, ts=30, flags=0000, data=5

Sep 27 11:36:29 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=968, did=0, tid=0, rid=0, sid=0,

nid=0

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=17, state=Idle: CAS event, ts=17, flags=0000, data=11

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=19, state=Idle: CAS event, ts=19, flags=0000, data=5

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=20, state=Idle: CAS event, ts=20, flags=0000, data=13

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=23, state=Idle: CAS event, ts=23, flags=0000, data=5

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=17, state=Idle: CAS event, ts=17, flags=0000, data=5

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=19, state=Idle: CAS event, ts=19, flags=0000, data=13

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=20, state=Idle: CAS event, ts=20, flags=0000, data=5

Sep 27 11:37:00 sip_ua[391]: fxs.cpp:431: ---> ts=23, state=Idle: CAS event, ts=23, flags=0000, data=1

Sep 27 11:37:00 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Idle: CAS event, ts=30, flags=0000, data=9

Sep 27 11:37:00 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Idle: CAS event, ts=30, flags=0000, data=5



root@comcerto:~# tcpdump -v -s1514 port 5060 and host 10.62.40.24

tcpdump: listening on eth0, link-type EN10MB (Ethernet), capture size 1514 bytes

11:31:03.288218 IP (tos 0x0, ttl 61, id 24461, offset 0, flags [DF], proto UDP (17), length 386)

    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 358

        OPTIONS sip:4920@10.62.113.132:5060 SIP/2.0

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-1-402-1718644525060-12252

        Max-Forwards: 70

        To: <sip:4920@10.62.40.24>

        From: <sip:u402@10.62.40.24>;tag=1-402

        Call-ID: 1-402-1718644525060-1-402

        CSeq: 2 OPTIONS

        Contact: <sip:10.62.40.24:5060>

        Accept: multipart/mixed, application/SDP

        Content-Length: 0

 

11:31:03.316170 IP (tos 0x0, ttl 61, id 24473, offset 0, flags [DF], proto UDP (17), length 353)

    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 325

        OPTIONS sip:10.62.113.132:5060 SIP/2.0

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-40334

        Max-Forwards: 70

        To: <sip:10.62.113.132>

        From: <sip:t3@10.62.40.24>;tag=3-3

        Call-ID: 3-3-777-3-3

        CSeq: 302508 OPTIONS

        Contact: <sip:10.62.40.24:5060>

        Accept: multipart/mixed, application/SDP

        Content-Length: 0

 

11:31:03.322876 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), length 326)

    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 298

        SIP/2.0 403 Forbidden

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-1-402-1718644525060-12252

        To: <sip:4920@10.62.40.24>

        From: <sip:u402@10.62.40.24>;tag=1-402

        Call-ID: 1-402-1718644525060-1-402

        CSeq: 2 OPTIONS

        Server: repro 1.12.0

        User-Agent: smg_pa_sip 3.20.3.5

        Content-Length: 0

 

11:31:03.328897 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), length 284)

    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 256

        SIP/2.0 200 OK

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-40334

        Contact: <sip:10.62.113.132:5060>

        To: <sip:10.62.113.132>;tag=f6115612

        From: <sip:t3@10.62.40.24>;tag=3-3

        Call-ID: 3-3-777-3-3

        CSeq: 302508 OPTIONS

        Content-Length: 0

 

11:31:33.316379 IP (tos 0x0, ttl 61, id 41940, offset 0, flags [DF], proto UDP (17), length 353)

    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 325

        OPTIONS sip:10.62.113.132:5060 SIP/2.0

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-41334

        Max-Forwards: 70

        To: <sip:10.62.113.132>

        From: <sip:t3@10.62.40.24>;tag=3-3

        Call-ID: 3-3-777-3-3

        CSeq: 302509 OPTIONS

        Contact: <sip:10.62.40.24:5060>

        Accept: multipart/mixed, application/SDP

        Content-Length: 0

 

11:31:33.325469 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), length 284)

    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 256

        SIP/2.0 200 OK

        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-41334

        Contact: <sip:10.62.113.132:5060>

        To: <sip:10.62.113.132>;tag=5eac7e55

        From: <sip:t3@10.62.40.24>;tag=3-3

        Call-ID: 3-3-777-3-3

        CSeq: 302509 OPTIONS

        Content-Length: 0

comment:18 by san, 6 days ago

Я снова не увидел вызова в дампе и попросил пользователя прислать лог и дамп одного и того же вызова.
Вот они:

tcpdump: listening on eth0, link-type EN10MB (Ethernet), capture size 1514 bytes
07:58:50.141341 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 582)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 554
        OPTIONS sip:4998@10.62.40.24 SIP/2.0
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d11cac4ece30                       306e;rport
        Via: SIP/2.0/UDP 10.62.4.17:5060;branch=z9hG4bK7e7da0b8;rport=5060
        Max-Forwards: 69
        Contact: <sip:10.62.4.17;transport=UDP>
        To: <sip:4998@10.62.113.132>
        From: "pbx"<sip:4998@10.62.4.17>;tag=as5bfac73
        Call-ID: 6842a0a649d38b9277d3811e4cc16e1a@10.62.4.17
        CSeq: 102 OPTIONS
        Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
        Date: Mon, 30 Sep 2024 08:08:50 GMT
        User-Agent: VINTEO 2.0.0
        Content-Length: 0

07:58:50.144805 IP (tos 0x0, ttl 61, id 35610, offset 0, flags [DF], proto UDP (17                       ), length 689)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 661
        SIP/2.0 200 OK
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d11cac4ece30                       306e;received=10.62.113.132;rport=5060
        Via: SIP/2.0/UDP 10.62.4.17:5060;branch=z9hG4bK7e7da0b8;rport=5060
        From: "pbx"<sip:4998@10.62.4.17>;tag=as5bfac73
        To: <sip:4998@10.62.113.132>
        Call-ID: 6842a0a649d38b9277d3811e4cc16e1a@10.62.4.17
        CSeq: 102 OPTIONS
        Supported: 100rel, replaces
        Allow: INVITE, ACK, BYE, CANCEL, PRACK, REGISTER, INFO, REFER, NOTIFY, OPT                       IONS, UPDATE
        Content-Type: application/sdp
        User-Agent: smg_pa_sip 3.22.3.8
        Content-Length: 108

        v=0
        o=- 1 1 IN IP4 10.62.40.24
        s=SMG SIP session
        c=IN IP4 10.62.40.24
        t=0 0
        m=audio 0 RTP/AVP 8 0 101
07:58:53.958811 IP (tos 0x0, ttl 61, id 38622, offset 0, flags [DF], proto UDP (17                       ), length 354)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 326
        OPTIONS sip:10.62.113.132:5060 SIP/2.0
        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-985304
        Max-Forwards: 70
        To: <sip:10.62.113.132>
        From: <sip:t3@10.62.40.24>;tag=3-3
        Call-ID: 3-3-777-3-3
        CSeq: 310727 OPTIONS
        Contact: <sip:10.62.40.24:5060>
        Accept: multipart/mixed, application/SDP
        Content-Length: 0

07:58:53.967239 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 285)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 257
        SIP/2.0 200 OK
        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-985304
        Contact: <sip:10.62.113.132:5060>
        To: <sip:10.62.113.132>;tag=a5bf4470
        From: <sip:t3@10.62.40.24>;tag=3-3
        Call-ID: 3-3-777-3-3
        CSeq: 310727 OPTIONS
        Content-Length: 0

07:59:00.054679 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 1448)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 1420
        INVITE sip:4390@10.62.40.24 SIP/2.0
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---050687364ac0                       fa1e;rport
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK1880198138
        Max-Forwards: 69
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        Contact: <sip:7031@127.0.0.1:6060>
        To: <sip:4390@10.62.113.132>
        From: <sip:7031@10.62.113.132>;tag=145387868
        Call-ID: 1814179202
        CSeq: 20 INVITE
        Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE, PRA                       CK, SUBSCRIBE
        Content-Type: application/sdp
        Supported: replaces, timer, 100rel
        User-Agent: eXosip/5.3.0
        Allow-Events: conference
        X-Endpoint-Type: FXO
        Content-Length: 750

        v=0
        o=gateway 0 24 IN IP4 10.62.113.132
        s=conversation
        c=IN IP4 10.62.113.132
        t=0 0
        m=audio 10060 RTP/SAVP 8 0 4 18 101 13 98
        a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:ybpzB1P4vdeaV4vaLyKvFTZCrh0mymyX                       d9ee0XXa
        a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:pcGgWQrU2VHqxD3aN5EsmMDz9P2lkBz0                       rnUUidAO
        a=rtpmap:8 PCMA/8000
        a=rtpmap:0 PCMU/8000
        a=rtpmap:4 G723/8000
        a=rtpmap:18 G729/8000
        a=rtpmap:101 telephone-event/8000
        a=fmtp:101 0-15
        a=rtpmap:13 CN/8000
        a=rtpmap:98 PCMA/8000
        a=gpmd:98 vbd=yes
        m=audio 10060 RTP/AVP 8 0 4 18 101 13 98
        a=rtpmap:8 PCMA/8000
        a=rtpmap:0 PCMU/8000
        a=rtpmap:4 G723/8000
        a=rtpmap:18 G729/8000
        a=rtpmap:101 telephone-event/8000
        a=fmtp:101 0-15
        a=rtpmap:13 CN/8000
        a=rtpmap:98 PCMA/8000
        a=gpmd:98 vbd=yes
07:59:00.058869 IP (tos 0x0, ttl 61, id 39119, offset 0, flags [DF], proto UDP (17                       ), length 456)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 428
        SIP/2.0 100 Trying
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---050687364ac0                       fa1e;received=10.62.113.132;rport=5060
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK1880198138
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        From: <sip:7031@10.62.113.132>;tag=145387868
        To: <sip:4390@10.62.113.132>
        Call-ID: 1814179202
        CSeq: 20 INVITE
        User-Agent: smg_pa_sip 3.22.3.8
        Content-Length: 0

07:59:00.062985 IP (tos 0x0, ttl 61, id 39121, offset 0, flags [DF], proto UDP (17                       ), length 614)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 586
        SIP/2.0 488 Not Acceptable Here
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---050687364ac0                       fa1e;received=10.62.113.132;rport=5060
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK1880198138
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        From: <sip:7031@10.62.113.132>;tag=145387868
        To: "4390" <sip:4390@10.62.113.132>;tag=i331p0D1058D0t3r86441
        Call-ID: 1814179202
        CSeq: 20 INVITE
        X-UniqueTag: 1100014b66fa5a44adb22d6be97bd001
        Reason: Q.850;cause=65;text="Bearer capability not implemented"
        User-Agent: smg_pa_sip 3.22.3.8
        Content-Length: 0

07:59:00.075218 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 331)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 303
        ACK sip:4390@10.62.40.24 SIP/2.0
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---050687364ac0                       fa1e;rport
        Max-Forwards: 70
        To: "4390" <sip:4390@10.62.113.132>;tag=i331p0D1058D0t3r86441
        From: <sip:7031@10.62.113.132>;tag=145387868
        Call-ID: 1814179202
        CSeq: 20 ACK
        Content-Length: 0

07:59:16.203932 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 1449)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 1421
        INVITE sip:4390@10.62.40.24 SIP/2.0
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d890f8787b42                       8e33;rport
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK2026117162
        Max-Forwards: 69
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        Contact: <sip:7031@127.0.0.1:6060>
        To: <sip:4390@10.62.113.132>
        From: <sip:7031@10.62.113.132>;tag=1477643992
        Call-ID: 1831317458
        CSeq: 20 INVITE
        Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE, PRA                       CK, SUBSCRIBE
        Content-Type: application/sdp
        Supported: replaces, timer, 100rel
        User-Agent: eXosip/5.3.0
        Allow-Events: conference
        X-Endpoint-Type: FXO
        Content-Length: 750

        v=0
        o=gateway 0 25 IN IP4 10.62.113.132
        s=conversation
        c=IN IP4 10.62.113.132
        t=0 0
        m=audio 10060 RTP/SAVP 8 0 4 18 101 13 98
        a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:so6+49CK8XaP/h0rPxX9Gh0R8YKAS2RD                       dNvvA1DB
        a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:kPZBroqF6J1KZOkpJR6xvG+YrHbc9EpN                       4ZHeuTeq
        a=rtpmap:8 PCMA/8000
        a=rtpmap:0 PCMU/8000
        a=rtpmap:4 G723/8000
        a=rtpmap:18 G729/8000
        a=rtpmap:101 telephone-event/8000
        a=fmtp:101 0-15
        a=rtpmap:13 CN/8000
        a=rtpmap:98 PCMA/8000
        a=gpmd:98 vbd=yes
        m=audio 10060 RTP/AVP 8 0 4 18 101 13 98
        a=rtpmap:8 PCMA/8000
        a=rtpmap:0 PCMU/8000
        a=rtpmap:4 G723/8000
        a=rtpmap:18 G729/8000
        a=rtpmap:101 telephone-event/8000
        a=fmtp:101 0-15
        a=rtpmap:13 CN/8000
        a=rtpmap:98 PCMA/8000
        a=gpmd:98 vbd=yes
07:59:16.207996 IP (tos 0x0, ttl 61, id 43544, offset 0, flags [DF], proto UDP (17                       ), length 457)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 429
        SIP/2.0 100 Trying
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d890f8787b42                       8e33;received=10.62.113.132;rport=5060
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK2026117162
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        From: <sip:7031@10.62.113.132>;tag=1477643992
        To: <sip:4390@10.62.113.132>
        Call-ID: 1831317458
        CSeq: 20 INVITE
        User-Agent: smg_pa_sip 3.22.3.8
        Content-Length: 0

07:59:16.210983 IP (tos 0x0, ttl 61, id 43546, offset 0, flags [DF], proto UDP (17                       ), length 616)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 588
        SIP/2.0 488 Not Acceptable Here
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d890f8787b42                       8e33;received=10.62.113.132;rport=5060
        Via: SIP/2.0/UDP 127.0.0.1:6060;rport=6060;branch=z9hG4bK2026117162
        Record-Route: <sip:10.62.113.132:5060;transport=udp;lr>
        From: <sip:7031@10.62.113.132>;tag=1477643992
        To: "4390" <sip:4390@10.62.113.132>;tag=i242p0D1147D0t3r235092
        Call-ID: 1831317458
        CSeq: 20 INVITE
        X-UniqueTag: 110000f266fa5a541704ebd2e7432001
        Reason: Q.850;cause=65;text="Bearer capability not implemented"
        User-Agent: smg_pa_sip 3.22.3.8
        Content-Length: 0

07:59:16.224361 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 333)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 305
        ACK sip:4390@10.62.40.24 SIP/2.0
        Via: SIP/2.0/UDP 10.62.113.132:5060;branch=z9hG4bK-524287-1---d890f8787b42                       8e33;rport
        Max-Forwards: 70
        To: "4390" <sip:4390@10.62.113.132>;tag=i242p0D1147D0t3r235092
        From: <sip:7031@10.62.113.132>;tag=1477643992
        Call-ID: 1831317458
        CSeq: 20 ACK
        Content-Length: 0

07:59:23.959071 IP (tos 0x0, ttl 61, id 44078, offset 0, flags [DF], proto UDP (17                       ), length 354)
    10.62.40.24.5060 > 10.62.113.132.5060: SIP, length: 326
        OPTIONS sip:10.62.113.132:5060 SIP/2.0
        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-986196
        Max-Forwards: 70
        To: <sip:10.62.113.132>
        From: <sip:t3@10.62.40.24>;tag=3-3
        Call-ID: 3-3-777-3-3
        CSeq: 310728 OPTIONS
        Contact: <sip:10.62.40.24:5060>
        Accept: multipart/mixed, application/SDP
        Content-Length: 0

07:59:23.968454 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto UDP (17), l                       ength 285)
    10.62.113.132.5060 > 10.62.40.24.5060: SIP, length: 257
        SIP/2.0 200 OK
        Via: SIP/2.0/UDP 10.62.40.24:5060;branch=z9hG4bK-u-3-3-777-986196
        Contact: <sip:10.62.113.132:5060>
        To: <sip:10.62.113.132>;tag=c35f1e4e
        From: <sip:t3@10.62.40.24>;tag=3-3
        Call-ID: 3-3-777-3-3
        CSeq: 310728 OPTIONS
        Content-Length: 0


14 packets captured
14 packets received by filter
0 packets dropped by kernel

Sep 30 07:58:39 syslogd started: BusyBox v1.18.5
Sep 30 07:58:52 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
5
Sep 30 07:58:55 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
13
Sep 30 07:58:57 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
5
Sep 30 07:58:59 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
13
Sep 30 07:59:00 sip_ua[391]: fxo.cpp:272: ts 30: dialing 4390
Sep 30 07:59:00 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out()  -> sip:4390@10.62.113.132...
Sep 30 07:59:00 sip_ua[444]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK188019
8138'
Sep 30 07:59:00 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!)
: cid=71, did=0, tid=83, rid=0, sid=0, nid=0
Sep 30 07:59:00 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Calling: Call disconnected, ts=30, flags=000
0, data=71
Sep 30 07:59:00 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream
Sep 30 07:59:00 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream
Sep 30 07:59:00 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Drop Line: CAS event, ts=30, flags=0000, dat
a=5
Sep 30 07:59:06 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Idle: CAS event, ts=30, flags=0000, data=13
Sep 30 07:59:08 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
5
Sep 30 07:59:11 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
13
Sep 30 07:59:13 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
5
Sep 30 07:59:16 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Ringing: CAS event, ts=30, flags=0000, data=
13
Sep 30 07:59:16 sip_ua[391]: fxo.cpp:272: ts 30: dialing 4390
Sep 30 07:59:16 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out()  -> sip:4390@10.62.113.132...
Sep 30 07:59:16 sip_ua[444]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK202611
7162'
Sep 30 07:59:16 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!)
: cid=72, did=0, tid=84, rid=0, sid=0, nid=0
Sep 30 07:59:16 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Calling: Call disconnected, ts=30, flags=000
0, data=72
Sep 30 07:59:16 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream
Sep 30 07:59:16 sip_ua[391]: comcerto.cpp:6995: ts 30: stopping RTP stream
Sep 30 07:59:16 sip_ua[391]: fxo.cpp:363: ---> ts=30, state=Drop Line: CAS event, ts=30, flags=0000, dat
a=5
Sep 30 07:59:28 syslogd exiting
Note: See TracTickets for help on using tickets.