Доброго времени суток!
Связь с одним из филиалов у нас осуществляется через пару IP-шлюзов DVG-6004S и DVG-5004S, настроенных в режиме Hotline (они подключены друг ко другу WAN-портами через свитч, без SIP-сервера). С недавнего времени без видимых причин переодически стали возникать проблемы: звонок срывается либо сразу после поднятия трубки на другом конце, либо после нескольких секунд разговора. Ненадолго помогает перезагрузка шлюзов и отключение, а затем включение Flash-event ( в настройках стоит RFC2823).

Логи из SLMON в момент обрыва звонка:
на DVG-6004:

11:16:54 [1527012] 0: Fxo Ringing
11:16:54 [1527012] 0: CallerId=
11:16:54 [1527012] 0: ==17:WaitAnswerDeviceOk
11:16:54 [1527013] 0: HotLineEnabled with [701]
11:16:54 [1527013] 0: End Input Default Route [0]
11:16:54 [1527013] 0: PhoneBook has GwNo=701, addr=10.0.0.1:5060
11:16:54 [1527013] 0: ==7:Inviting
11:16:54 [1527013] 0: VoIP CallOut, Invite <sip:701@10.0.0.1:5060;user=phone>
11:16:54 [1527014] 0: 601=DIALING
11:16:55 [1527019] 0: RingTime(86) First Stop
11:16:59 [1527060] 0: RingTime(512) Second Ring
11:17:02 [1527093] 0: Invite timeout, Onhook ===
11:17:02 [1527093] 0: Offhook Fxo for PlayBusy
11:17:02 [1527093] 0: FxoHookOff
11:17:02 [1527093] 0: SetInputGain(4)
11:17:02 [1527093] 0: ==15:PlayBusyTone
11:17:05 [1527123] DSP_ch0_check=0
11:17:05 [1527123] 0: FxoHookOn
11:17:05 [1527123] 0: ==3:Idle
11:17:05 [1527124] 0: SetInputGain(4)
11:17:05 [1527124] 0: SetFax(1)=0


на DVG-5004:

11:16:54 [023185] 0: 701=OFFERING
11:16:54 [023185] 0: Get CallerId=601
11:16:54 [023185] 0: Peer=10.0.0.2:9000, PT=18, RecvOnly=0
11:16:54 [023185] 0: ==12:Alerting
11:16:57 [023208] 0: Fxs Pickup
11:16:57 [023208] 0: 701=ACCEPT
11:16:57 [023208] 0: ==13:VoipAnswering
11:17:12 [023358] DSP_ch0_check=0
11:17:12 [023359] 0: Fxs Hangup
11:17:12 [023359] 0: ==3:Idle
11:17:12 [023359] 0: SetFax(1)=0
11:17:16 [023397] 0: 701=DISCONNECT
11:17:16 [023397] 0: Release Old Dlg #2

Как можно решить проблему?

У вас 6004 не получает invite от 5004.
По возможности выложите дамп трафика между шлюза

Эти логи записаны при звонке с 6004 на 5004. По идее, при этом INVITE должен получать 5004 от 6004 ?

не получает ответ на invite

После перезагрузки шлюзов звонки проходят нормально. Через некоторое время проблема появляется вновь. Посоветуйте хотя бы, в каком направлении искать решение, плз !

Логи при нормальной работе (дамп трафика, к сожалению, выложить не могу):
DVG-6004:
17:10:03 [011848] 2: Fxo Ringing
17:10:03 [011848] 2: CallerId=
17:10:03 [011848] 2: ==17:WaitAnswerDeviceOk
17:10:03 [011848] 2: HotLineEnabled with [703]
17:10:03 [011848] 2: End Input Default Route [0]
17:10:03 [011848] 2: PhoneBook has GwNo=703, addr=10.0.0.1:5060
17:10:03 [011848] 2: ==7:Inviting
17:10:03 [011848] 2: VoIP CallOut, Invite <sip:703@10.0.0.1:5060;user=phone>
17:10:03 [011850] 2: 603=DIALING
17:10:03 [011851] 2: 603=PROCEEDING
17:10:03 [011852] 2: 603=RINGBACK
17:10:03 [011852] 2: ==8:RingBack
17:10:04 [011854] 2: RingTime(83) First Stop
17:10:08 [011896] 2: RingTime(512) Second Ring
17:10:12 [011938] 2: 603=CONNECTED
17:10:12 [011938] 2: Peer=10.0.0.1:9004, PT=18, RecvOnly=0
17:10:12 [011938] 2: FxoHookOff
17:10:12 [011938] 2: SetInputGain(4)
17:10:12 [011938] 2: RtpApiTalk[0,0], Peer=10.0.0.1:9004, PT=18, FC=2, NewOOB=1
17:10:12 [011938] 2: ==14:Talking
17:10:12 [011939] 2: SetTalkMode[1,1]
17:13:59 [014205] 2: Fxo Detected CPT Busy(5)
17:13:59 [014205] 2: Bye at Talking
17:13:59 [014206] 2: ==15:PlayBusyTone
17:13:59 [014206] DSP_ch2_check=0
17:13:59 [014206] 2: FxoHookOn
17:13:59 [014206] 2: ==3:Idle
17:13:59 [014206] 2: SetInputGain(4)
17:13:59 [014206] 2: SetFax(1)=0
17:13:59 [014207] 2: 603=DISCONNECT
17:13:59 [014207] 2: Release Old Dlg #2


DVG-5004:
17:10:03 [207504] 2: 703=OFFERING
17:10:03 [207504] 2: Get CallerId=603
17:10:03 [207504] 2: Peer=10.0.0.2:9004, PT=18, RecvOnly=0
17:10:03 [207504] 2: ==12:Alerting
17:10:08 [207553] 2: Fxs Pickup
17:10:08 [207554] 2: 703=ACCEPT
17:10:08 [207554] 2: ==13:VoipAnswering
17:10:09 [207568] DSP_ch0_check=0
17:10:09 [207568] 0: Fxs Hangup
17:10:09 [207569] 0: ==3:Idle
17:10:09 [207569] 0: SetFax(1)=0
17:10:11 [207592] 2: 703=CONNECTED
17:10:11 [207592] 2: RtpApiTalk[1,1], Peer=10.0.0.2:9004, PT=18, FC=2, NewOOB=1
17:10:11 [207592] 2: ==14:Talking
17:13:58 [209860] 2: 703=DISCONNECT
17:13:58 [209860] 2: Release Active Dlg
17:13:58 [209860] 2: ==15:PlayBusyTone


При обрыве звонков логи выглядят так:

DVG-6004
11:40:16 [678026] 0: Fxo Ringing
11:40:16 [678026] 0: CallerId=
11:40:16 [678026] 0: ==17:WaitAnswerDeviceOk
11:40:16 [678026] 0: HotLineEnabled with [701]
11:40:16 [678026] 0: End Input Default Route [0]
11:40:16 [678026] 0: PhoneBook has GwNo=701, addr=10.0.0.1:5060
11:40:16 [678026] 0: ==7:Inviting
11:40:16 [678026] 0: VoIP CallOut, Invite <sip:701@10.0.0.1:5060;user=phone>
11:40:16 [678027] 0: 601=DIALING
11:40:16 [678028] 0: 601=PROCEEDING
11:40:16 [678029] 0: 601=RINGBACK
11:40:16 [678029] 0: ==8:RingBack
11:40:16 [678032] 0: RingTime(86) First Stop
11:40:20 [678073] 0: RingTime(513) Second Ring
11:40:21 [678079] 0: 601=CONNECTED
11:40:21 [678079] 0: Peer=10.0.0.1:9000, PT=18, RecvOnly=0
11:40:21 [678079] 0: FxoHookOff
11:40:21 [678079] 0: SetInputGain(4)
11:40:21 [678079] 0: RtpApiTalk[0,0], Peer=10.0.0.1:9000, PT=18, FC=2, NewOOB=1
11:40:21 [678079] 0: ==14:Talking
11:40:21 [678082] 0: SetTalkMode[1,1]
11:40:33 [678195] 0: Fxo Detected BTC(3)
11:40:33 [678195] 0: Bye at Talking
11:40:33 [678195] 0: ==15:PlayBusyTone
11:40:33 [678195] DSP_ch0_check=0
11:40:33 [678195] 0: FxoHookOn
11:40:33 [678195] 0: ==3:Idle
11:40:33 [678196] 0: SetInputGain(4)
11:40:33 [678196] 0: SetFax(1)=0
11:40:33 [678196] 0: 601=DISCONNECT
11:40:33 [678196] 0: Release Old Dlg #2
11:40:36 [678229] 0: 601=OFFERING
11:40:36 [678229] 0: Get CallerId=701
11:40:36 [678229] 0: Peer=10.0.0.1:9000, PT=18, RecvOnly=0
11:40:36 [678229] 0: TrunkPrefix=, Dest=, Dialno=
11:40:36 [678229] 0: FxoHookOff
11:40:36 [678229] 0: SetInputGain(4)
11:40:36 [678229] 0: ==18:TrunkDialOut
11:40:36 [678230] 0: Fxo DialOut OK
11:40:36 [678231] 0: 601=ACCEPT
11:40:36 [678231] 0: ==13:VoipAnswering
11:40:37 [678238] 0: 601=CONNECTED
11:40:37 [678238] 0: RtpApiTalk[1,1], Peer=10.0.0.1:9000, PT=18, FC=2, NewOOB=1
11:40:37 [678238] 0: ==14:Talking
11:40:39 [678255] 0: Play(1)
11:40:40 [678273] 0: Play(0)
11:40:51 [678378] 0: 601=DISCONNECT
11:40:51 [678378] 0: Release Active Dlg
11:40:51 [678378] 0: ==15:PlayBusyTone
11:40:51 [678378] 0: Start Btc Learning
11:40:52 [678385] 0: 601=OFFERING
11:40:52 [678385] 0: Get CallerId=701
11:40:52 [678385] 0: Busy now, Reject this call
11:40:52 [678385] 0: 601=REJECT
11:40:52 [678386] 0: 601=DISCONNECT
11:40:52 [678386] 0: Release Old Dlg #2
11:40:53 [678396] 0: Fxo Detected CPT Busy(5)
11:40:53 [678396] DSP_ch0_check=0
11:40:53 [678396] 0: FxoHookOn
11:40:53 [678396] DSP_ch0_check=0
11:40:53 [678396] 0: FxoHookOn
11:40:53 [678396] 0: ==3:Idle
11:40:53 [678396] 0: SetInputGain(4)
11:40:53 [678396] 0: SetFax(1)=0
11:40:59 [678455] 0: 601=OFFERING
11:40:59 [678455] 0: Get CallerId=701
11:40:59 [678455] 0: Peer=10.0.0.1:9000, PT=18, RecvOnly=0
11:40:59 [678455] 0: TrunkPrefix=, Dest=, Dialno=
11:40:59 [678455] 0: FxoHookOff
11:40:59 [678455] 0: SetInputGain(4)
11:40:59 [678455] 0: ==18:TrunkDialOut
11:40:59 [678456] 0: Fxo DialOut OK
11:40:59 [678456] 0: 601=ACCEPT
11:40:59 [678457] 0: ==13:VoipAnswering
11:40:59 [678458] 0: 601=CONNECTED
11:40:59 [678458] 0: RtpApiTalk[1,1], Peer=10.0.0.1:9000, PT=18, FC=2, NewOOB=1
11:40:59 [678458] 0: ==14:Talking
11:41:00 [678470] 0: 601=DISCONNECT
11:41:00 [678470] 0: Release Active Dlg
11:41:00 [678470] 0: ==15:PlayBusyTone
11:41:00 [678470] 0: Start Btc Learning
11:41:02 [678483] 0: 601=OFFERING
11:41:02 [678483] 0: Get CallerId=701
11:41:02 [678483] 0: Busy now, Reject this call
11:41:02 [678483] 0: 601=REJECT
11:41:02 [678484] 0: 601=DISCONNECT
11:41:02 [678484] 0: Release Old Dlg #2
11:41:02 [678485] 0: Btc Learning Exception No Change
11:41:02 [678485] DSP_ch0_check=0
11:41:02 [678485] 0: FxoHookOn
11:41:02 [678485] 0: ==3:Idle
11:41:02 [678485] 0: SetInputGain(4)
11:41:02 [678485] 0: SetFax(1)=0
11:41:02 [678493] 0: 601=OFFERING
11:41:03 [678493] 0: Get CallerId=701
11:41:03 [678494] 0: Peer=10.0.0.1:9000, PT=18, RecvOnly=0
11:41:03 [678494] 0: TrunkPrefix=, Dest=, Dialno=
11:41:03 [678494] 0: FxoHookOff
11:41:03 [678494] 0: SetInputGain(4)
11:41:03 [678494] 0: ==18:TrunkDialOut
11:41:03 [678494] 0: Fxo DialOut OK
11:41:03 [678495] 0: 601=ACCEPT
11:41:03 [678495] 0: ==13:VoipAnswering
11:41:03 [678497] 0: 601=CONNECTED
11:41:03 [678497] 0: RtpApiTalk[1,1], Peer=10.0.0.1:9000, PT=18, FC=2, NewOOB=1
11:41:03 [678497] 0: ==14:Talking
11:41:06 [678525] 0: Play(1)
11:41:07 [678543] 0: Play(0)
11:41:09 [678555] 0: Play(4)
11:41:40 [678867] 0: 601=DISCONNECT

DVG-5004:
11:40:16 [873687] 0: 701=OFFERING
11:40:16 [873687] 0: Get CallerId=601
11:40:16 [873687] 0: Peer=10.0.0.2:9000, PT=18, RecvOnly=0
11:40:16 [873687] 0: ==12:Alerting
11:40:17 [873700] 0: Fxs Pickup
11:40:17 [873700] 0: 701=ACCEPT
11:40:17 [873700] 0: ==13:VoipAnswering
11:40:21 [873738] 0: 701=CONNECTED
11:40:21 [873738] 0: RtpApiTalk[1,1], Peer=10.0.0.2:9000, PT=18, FC=2, NewOOB=1
11:40:21 [873738] 0: ==14:Talking
11:40:33 [873854] 0: 701=DISCONNECT
11:40:33 [873854] 0: Release Active Dlg
11:40:33 [873854] 0: ==15:PlayBusyTone
11:40:35 [873881] DSP_ch0_check=0
11:40:35 [873881] 0: Fxs Hangup
11:40:35 [873882] 0: ==3:Idle
11:40:36 [873882] 0: SetFax(1)=0
11:40:36 [873885] 0: Fxs Pickup!
11:40:36 [873885] 0: HotLineEnabled with [601]
11:40:36 [873885] 0: End Input Default Route [0]
11:40:36 [873885] 0: PhoneBook has GwNo=601, addr=10.0.0.2:5060
11:40:36 [873885] 0: ==7:Inviting
11:40:36 [873885] 0: VoIP CallOut, Invite <sip:601@10.0.0.2:5060;user=phone>
11:40:36 [873886] 0: 701=DIALING
11:40:36 [873887] 0: 701=PROCEEDING
11:40:36 [873890] 0: 701=CONNECTED
11:40:36 [873890] 0: Peer=10.0.0.2:9000, PT=18, RecvOnly=0
11:40:36 [873890] 0: RtpApiTalk[1,1], Peer=10.0.0.2:9000, PT=18, FC=2, NewOOB=1
11:40:36 [873890] 0: ==14:Talking
11:40:39 [873913] 0: Get(1)
11:40:40 [873931] 0: Get(0)
11:40:42 [873943] 0: Get(4)
11:40:51 [874035] DSP_ch0_check=0
11:40:51 [874035] 0: Fxs Hangup
11:40:51 [874035] 0: Bye at Talking
11:40:51 [874035] 0: ==3:Idle
11:40:51 [874036] 0: SetFax(1)=0
11:40:51 [874037] 0: 701=DISCONNECT
11:40:51 [874037] 0: Release Old Dlg #2
11:40:51 [874040] 0: Fxs Pickup!
11:40:51 [874040] 0: HotLineEnabled with [601]
11:40:51 [874040] 0: End Input Default Route [0]
11:40:51 [874041] 0: PhoneBook has GwNo=601, addr=10.0.0.2:5060
11:40:51 [874041] 0: ==7:Inviting
11:40:51 [874041] 0: VoIP CallOut, Invite <sip:601@10.0.0.2:5060;user=phone>
11:40:52 [874042] 0: 701=DIALING
11:40:52 [874043] 0: 701=PROCEEDING
11:40:52 [874044] 0: 701=BUSY
11:40:52 [874044] 0: Release Active Dlg
11:40:52 [874044] 0: ==15:PlayBusyTone
11:40:52 [874044] 0: 701=DISCONNECT
11:40:52 [874044] 0: Release Old Dlg #2
11:40:53 [874058] DSP_ch0_check=0
11:40:53 [874058] 0: Fxs Hangup
11:40:53 [874058] 0: ==3:Idle
11:40:53 [874058] 0: SetFax(1)=0
11:40:53 [874061] 0: Fxs Pickup!
11:40:53 [874061] 0: HotLineEnabled with [601]
11:40:53 [874061] 0: End Input Default Route [0]
11:40:53 [874061] 0: PhoneBook has GwNo=601, addr=10.0.0.2:5060
11:40:53 [874061] 0: ==7:Inviting
11:40:54 [874061] 0: VoIP CallOut, Invite <sip:601@10.0.0.2:5060;user=phone>
11:40:54 [874062] 0: 701=DIALING
11:40:56 [874092] DSP_ch0_check=0
11:40:57 [874092] 0: Fxs Hangup
11:40:57 [874092] 0: ### NewStatus Overlap: PlayBusyTone->PlayBusyTone ###
11:40:57 [874092] 0: ==3:Idle
11:40:57 [874092] 0: SetFax(1)=0
11:40:57 [874094] 0: Fxs Pickup!
11:40:57 [874094] 0: HotLineEnabled with [601]
11:40:57 [874094] 0: End Input Default Route [0]
11:40:57 [874094] 0: PhoneBook has GwNo=601, addr=10.0.0.2:5060
11:40:57 [874094] 0: ==7:Inviting
11:40:57 [874095] 0: VoIP CallOut, Invite <sip:601@10.0.0.2:5060;user=phone>
11:40:57 [874096] 0: 701=DIALING
11:40:59 [874113] 0: 701=PROCEEDING
11:40:59 [874116] 0: 701=CONNECTED
11:40:59 [874116] 0: Peer=10.0.0.2:9000, PT=18, RecvOnly=0
11:40:59 [874116] 0: RtpApiTalk[1,1], Peer=10.0.0.2:9000, PT=18, FC=2, NewOOB=1
11:40:59 [874116] 0: ==14:Talking
11:41:00 [874127] DSP_ch0_check=0
11:41:00 [874127] 0: Fxs Hangup
11:41:00 [874127] 0: Bye at Talking
11:41:00 [874127] 0: ==3:Idle
11:41:00 [874127] 0: SetFax(1)=0
11:41:00 [874129] 0: 701=DISCONNECT
11:41:00 [874129] 0: Release Old Dlg #2
11:41:01 [874141] 0: 701=PROCEEDING
11:41:02 [874142] 0: 701=BUSY
11:41:02 [874142] 0: Error Dlg #6
11:41:02 [874142] 0: 701=DISCONNECT
11:41:02 [874142] 0: Release Old Dlg #2
11:41:02 [874149] 0: Fxs Pickup
11:41:02 [874149] 0: HotLineEnabled with [601]
11:41:02 [874149] 0: End Input Default Route [0]
11:41:02 [874149] 0: PhoneBook has GwNo=601, addr=10.0.0.2:5060
11:41:02 [874149] 0: ==7:Inviting
11:41:02 [874149] 0: VoIP CallOut, Invite <sip:601@10.0.0.2:5060;user=phone>
11:41:02 [874151] 0: 701=DIALING
11:41:02 [874152] 0: 701=PROCEEDING
11:41:03 [874155] 0: 701=CONNECTED
11:41:03 [874155] 0: Peer=10.0.0.2:9000, PT=18, RecvOnly=0
11:41:03 [874155] 0: RtpApiTalk[1,1], Peer=10.0.0.2:9000, PT=18, FC=2, NewOOB=1
11:41:03 [874155] 0: ==14:Talking
11:41:06 [874184] 0: Get(1)
11:41:07 [874201] 0: Get(0)
11:41:09 [874213] 0: Get(4)
11:41:40 [874524] DSP_ch0_check=0
11:41:40 [874524] 0: Fxs Hangup
11:41:40 [874524] 0: Bye at Talking
11:41:40 [874524] 0: ==3:Idle
11:41:40 [874524] 0: SetFax(1)=0
11:41:40 [874526] 0: 701=DISCONNECT
11:41:40 [874526] 0: Release Old Dlg #2

Есть подозрения что у вас срабатывает обнаружение сигнала "занято"
Вы его как-либо настраивали?

PS. Для начала отключите второй профиль CPT

_________________
Я хочу изменить мир к лучшему, но Бог не дает мне исходники (придумал не я)
Asterisk + DVG-4032S + 8xDVG-2032S + ATA Panasonic

_________________
С уважением,
Виктор Колосов

Меня больше смутило:



И наверное там еще и BTC AutoLearning включен

_________________
Я хочу изменить мир к лучшему, но Бог не дает мне исходники (придумал не я)
Asterisk + DVG-4032S + 8xDVG-2032S + ATA Panasonic

Да, только в данном случае шлюз уже освобождает порт.

_________________
С уважением,
Виктор Колосов

Друзья, хотелось бы конкретных рекомендаций типа "Сделай то и то..."
Обнаружение сигнала ЗАНЯТО никак не настраивалось.

профиль CPT#2 отключил, Autolearning BTC на 6004 отключил. Не помогло.

К сожалению не имею достоверной информации об алгоритмах обнаружения сигнала занято в этих шлюзах. Все приходится проверять на месте.
Информацию полученную опытным путем можете прочитать тут: http://forum.dlink.ru/viewtopic.php?p=481656#481656
По поводу вашего случая: поскольку у вас был включен Autolearning BTC, возможно что в одну из строк BTC "автообучились" неправильные параметры, которые вызывают ложные срабатывания. Обнулите все, кроме настроенных под ваши тоны "занято".
Если у вас в момент обрыва в slmon регулярно появляется Fxo Detected BTC(3), возможно будет достаточно обнулить только 4-ю строку BTC

_________________
Я хочу изменить мир к лучшему, но Бог не дает мне исходники (придумал не я)
Asterisk + DVG-4032S + 8xDVG-2032S + ATA Panasonic