У одного из абонентов стоит шлюз dvg 5008S. Проблема состоит в следующем, на один из портов шлюза долго нельзя дозвониться идёт занятость, а статус показывает что он свободен, с попытки четвёртой идёт посылка вызова.

Выложите лог со шлюза.

_________________
логи можно снять
[url]http://ftp.dlink.ru/pub/VoIP/DVG-6004S/Firmware/slmon(1225112422).rar[/url]

14:04:20 *** SLMON start to log ***
14:04:33 [035526] Receive Cmd=1100, ParaLen=0, Para=[]
14:04:33 [035526] == Ver(1.02.37.61.8258 2008/02/20 08:19:47) PId(242.DLink.NoPrefix.drtp.vlan) Drv(0.9.5.1.735 29/Nov/2007 10:30:08) Hw(SA7S8) == 0.6.7-1
14:04:44 [035660] DSP_ch0_check=0
14:04:44 [035660] 0: Fxs Hangup
14:04:44 [035660] 0: Bye at Talking
14:04:44 [035661] 0: ==3:Idle
14:04:44 [035661] 0: SetFax(1)=0
14:04:44 [035661] 0: 615262=DISCONNECT
14:04:44 [035661] 0: Release Old Dlg #2
14:06:05 [036470] 0: 615262=OFFERING
14:06:05 [036470] 0: Get CallerId=3532533398
14:06:05 [036470] 0: Peer=10.0.2.1:17236, PT=18, RecvOnly=0
14:06:05 [036470] 0: ==9:FxsCallerIdShortRing
14:06:05 [036475] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:06:05 [036475] 0: ==10:FxsCallerIdSending
14:06:07 [036494] 0: ==11:FxsCallerIdSent
14:06:07 [036496] 0: After CallerId Sent, SetFxsRing()=0
14:06:07 [036496] 0: ==12:Alerting
14:06:15 [036571] 0: Fxs Pickup
14:06:15 [036571] 0: 615262=ACCEPT
14:06:15 [036572] 0: ==13:VoipAnswering
14:06:15 [036572] 0: 615262=CONNECTED
14:06:15 [036572] 0: RtpApiTalk[1,1], Peer=10.0.2.1:17236, PT=18, FC=6, NewOOB=0
14:06:15 [036572] 0: ==14:Talking
14:06:17 [036592] 0: 615262=DISCONNECT
14:06:17 [036593] 0: Release Active Dlg
14:06:17 [036593] 0: ==15:PlayBusyTone
14:06:19 [036617] DSP_ch0_check=0
14:06:19 [036617] 0: Fxs Hangup
14:06:19 [036617] 0: ==3:Idle
14:06:20 [036617] 0: SetFax(1)=0
14:06:43 [036854] 0: 615262=OFFERING
14:06:43 [036854] 0: Get CallerId=3532533398
14:06:43 [036854] 0: Peer=10.0.2.1:18804, PT=18, RecvOnly=0
14:06:43 [036854] 0: ==9:FxsCallerIdShortRing
14:06:44 [036858] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:06:44 [036858] 0: ==10:FxsCallerIdSending
14:06:46 [036878] 0: ==11:FxsCallerIdSent
14:06:46 [036881] 0: 615262=DISCONNECT
14:06:46 [036881] 0: Release Active Dlg
14:06:46 [036881] 0: ==3:Idle
14:06:46 [036881] 0: SetFax(1)=0
14:11:42 *** SLMON start to log ***
14:11:52 10.8.121.22:3656 is monitoring , reject 10.8.121.22:3917
14:12:02 [040044] Receive Cmd=1100, ParaLen=0, Para=[]
14:12:02 [040044] == Ver(1.02.37.61.8258 2008/02/20 08:19:47) PId(242.DLink.NoPrefix.drtp.vlan) Drv(0.9.5.1.735 29/Nov/2007 10:30:08) Hw(SA7S8) == 0.6.7-1
14:12:30 [040319] 0: 615262=OFFERING
14:12:30 [040319] 0: Get CallerId=3532533398
14:12:30 [040320] 0: Peer=10.0.2.1:17526, PT=18, RecvOnly=0
14:12:30 [040320] 0: ==9:FxsCallerIdShortRing
14:12:30 [040324] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:12:30 [040324] 0: ==10:FxsCallerIdSending
14:12:32 [040344] 0: ==11:FxsCallerIdSent
14:12:32 [040346] 0: After CallerId Sent, SetFxsRing()=0
14:12:32 [040346] 0: ==12:Alerting
14:12:33 [040356] 0: 615262=DISCONNECT
14:12:33 [040356] 0: Release Active Dlg
14:12:33 [040356] 0: ==3:Idle
14:12:33 [040356] 0: SetFax(1)=0
14:12:39 [040411] 0: 615262=OFFERING
14:12:39 [040411] 0: Get CallerId=3532533398
14:12:39 [040411] 0: Peer=10.0.2.1:17666, PT=18, RecvOnly=0
14:12:39 [040411] 0: ==9:FxsCallerIdShortRing
14:12:39 [040415] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:12:39 [040415] 0: ==10:FxsCallerIdSending
14:12:41 [040433] 0: 615262=DISCONNECT
14:12:41 [040433] 0: Release Active Dlg
14:12:41 [040433] 0: ==3:Idle
14:12:41 [040433] 0: StopSendFskCallerId #4----------
14:12:41 [040433] 0: SetFax(1)=0
14:12:42 [040446] 0: Fxs Pickup
14:12:42 [040446] 0: ==4:GetDtmf
14:12:45 [040470] DSP_ch0_check=0
14:12:45 [040470] 0: Fxs Hangup
14:12:45 [040470] 0: ==3:Idle
14:12:45 [040470] 0: SetFax(1)=0
14:12:55 [040571] 0: 615262=OFFERING
14:12:55 [040571] 0: Get CallerId=3532533398
14:12:55 [040571] 0: Peer=10.0.2.1:16392, PT=18, RecvOnly=0
14:12:55 [040571] 0: ==9:FxsCallerIdShortRing
14:12:55 [040575] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:12:55 [040575] 0: ==10:FxsCallerIdSending
14:12:57 [040594] 0: 615262=DISCONNECT
14:12:57 [040594] 0: Release Active Dlg
14:12:57 [040594] 0: ==3:Idle
14:12:57 [040594] 0: StopSendFskCallerId #4----------
14:12:57 [040594] 0: SetFax(1)=0
14:12:59 [040611] 0: Fxs Pickup
14:12:59 [040611] 0: ==4:GetDtmf
14:13:01 [040634] DSP_ch0_check=0
14:13:01 [040634] 0: Fxs Hangup
14:13:01 [040634] 0: ==3:Idle
14:13:01 [040634] 0: SetFax(1)=0
14:13:22 [040840] 0: Fxs Pickup
14:13:22 [040840] 0: ==4:GetDtmf
14:13:23 [040850] DSP_ch0_check=0
14:13:23 [040850] 0: Fxs Hangup
14:13:23 [040850] 0: ==3:Idle
14:13:23 [040850] 0: SetFax(1)=0
14:13:52 [041138] 0: 615262=OFFERING
14:13:52 [041138] 0: Get CallerId=3532533398
14:13:52 [041138] 0: Peer=10.0.2.1:18288, PT=18, RecvOnly=0
14:13:52 [041138] 0: ==9:FxsCallerIdShortRing
14:13:52 [041143] 0: Fxs Send FskCallerId(3532533398,3532533398,)=0
14:13:52 [041143] 0: ==10:FxsCallerIdSending
14:13:54 [041162] 0: ==11:FxsCallerIdSent
14:13:54 [041164] 0: After CallerId Sent, SetFxsRing()=0
14:13:54 [041164] 0: ==12:Alerting
14:13:55 [041177] 0: 615262=DISCONNECT
14:13:55 [041177] 0: Release Active Dlg
14:13:55 [041177] 0: ==3:Idle
14:13:56 [041177] 0: SetFax(1)=0
14:14:01 [041230] 0: Fxs Pickup
14:14:01 [041230] 0: ==4:GetDtmf
14:14:04 [041259] DSP_ch0_check=0
14:14:04 [041259] 0: Fxs Hangup
14:14:04 [041259] 0: ==3:Idle
14:14:04 [041259] 0: SetFax(1)=0
14:14:10 [041324] 0: Fxs Pickup
14:14:10 [041324] 0: ==4:GetDtmf
14:14:11 [041335] DSP_ch0_check=0
14:14:11 [041335] 0: Fxs Hangup
14:14:11 [041335] 0: ==3:Idle
14:14:11 [041335] 0: SetFax(1)=0
14:14:14 [041366] 0: Fxs Pickup
14:14:14 [041366] 0: ==4:GetDtmf
14:14:33 [041556] 0: DTMF Timeout #1
14:14:33 [041556] 0: End Input Route [0]
14:14:33 [041556] 0: Line DialNum_New [83532533398]
14:14:33 [041556] 0: OutPinCode=<>, dial_no=<83532533398>
14:14:33 [041556] 0: PhoneBook has GwNo=3532533398, addr=10.0.2.1:5060
14:14:33 [041556] 0: ==7:Inviting
14:14:34 [041556] 0: VoIP CallOut, Invite <sip:3532533398@10.0.2.1:5060;user=phone>
14:14:34 [041557] 0: 615262=DIALING
14:14:34 [041558] 0: 615262=PROCEEDING
14:14:34 [041559] 0: 615262=RINGBACK
14:14:34 [041559] 0: UACSTATE_RINGBACK get SDP
14:14:34 [041559] 0: Peer=10.0.2.1:19352, PT=18, RecvOnly=0
14:14:34 [041559] 0: RtpApiTalk[1,1], Peer=10.0.2.1:19352, PT=18, FC=6, NewOOB=0
14:14:34 [041559] 0: ==14:Talking
14:14:38 [041599] DSP_ch0_check=0
14:14:38 [041600] 0: Fxs Hangup
14:14:38 [041600] 0: Bye at Talking
14:14:38 [041600] 0: 615262=CANCEL
14:14:38 [041600] 0: ==3:Idle
14:14:38 [041600] 0: SetFax(1)=0
14:14:38 [041601] 0: 615262=DISCONNECT
14:14:38 [041601] 0: Release Old Dlg #2

В какой момент не проходил вызов?

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

А индикаторы на шлюзе хоть что нибудь показывают? Дамп трафика можете выложить?

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