OXE f5.401.21.f

TG Тип ISDN с t2 spec. SIP

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

1243935931 -> [ipc_thread] IPC Thread : Ipc reception.
1243935931 -> [ipc_thread] IPC Thread : TCL thread signaled.
1243935931 -> [exec_ipc] in.
1243935931 -> [display_ipc_in] ------------ Begin ---------------
1243935931 -> neqt : 1592
1243935931 -> INVITE
1243935931 -> REQUEST URI : 89274167850@172.16.1.9:5060 ; user=name
1243935931 -> FROM : <7406> 7406@172.16.1.3:5060
1243935931 -> TO : <> 89274167850@172.16.1.9:5060
1243935931 -> CAC : 0
1243935931 -> neqt CAC : -1
1243935931 -> CLIR : 0
1243935931 -> SDP :
1243935931 -> ADDRESS : 172.16.1.16 :32514
1243935931 -> ALGOS :
1243935931 -> G723
1243935931 -> PCMA
1243935931 -> PCMU
1243935931 -> 97
1243935931 -> DIRECTION : SEND & RECEIVE
1243935931 -> N_GW_EXT : 0
1243935931 -> [display_ipc_in] ------------- End ----------------
1243935931 -> [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 1592.
1243935931 -> [CMotorCallManager::onIncomingEvent] New Call created.
1243935931 -> [CMotorCallManager::callFactory].
1243935931 -> [CMotorCallManager::callFactory]=835ec88.
1243935931 -> [CMotorCallManager::onIncomingEvent] eqt=1592.
1243935931 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 1592 inserted.
1243935931 -> [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
1243935931 -> [onIncomingEvent] INVITE arrived.
1243935931 -> [receiveInviteEvent] Call: eqt: 1592 INITIAL_STATE received an event.
1243935931 -> [emitInviteMessage] begin.
1243935931 -> [isValidGwExt] ext gw 0 is valid
1243935931 -> [quoteString] => "7406"
1243935931 -> [emitInviteMessage] P-Asserted-Identity: "7406" <sip:7406@172.16.1.3>
1243935931 -> [emitInviteMessage] From: "7406" <sip:7406@172.16.1.3>
1243935931 -> [emitInviteMessage] reqUri :sip:89274167850@172.16.1.9
1243935931 -> [emitInviteMessage] Invite directed to external gw : 0
1243935931 -> [emitInviteMessage] Request Uri : sip:89274167850@172.16.1.9
1243935931 -> [emitInviteMessage] To: <sip:89274167850@172.16.1.9>
1243935931 -> [emitInviteMessage] Contact: <sip:172.16.1.3;transport=TCP>
1243935931 -> [makeRequestSdp] Begin sdp : v=0
o=OXE 1243935931 1243935931 IN IP4 172.16.1.3
s=-
c=IN IP4 0.0.0.0
t=0 0
.
1243935931 -> [makeRequestSdp] Direction: 0.
1243935931 -> [makeResponseSdp] format 97
1243935931 -> [makeRequestSdp] Final sdp: v=0
o=OXE 1243935931 1243935931 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.16
t=0 0
m=audio 32514 RTP/AVP 4 8 0 97
a=sendrecv
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:97 telephone-event/8000
.
1243935931 -> [emitInviteMessage] The sdp build: .
1243935931 -> [CMotorCall::determineTargetsForTheRequest] is not Responsible For This URI.
1243935931 -> [makeInitialMotorRequest] nb contacts=1.
1243935931 -> [makeInitialMotorRequest] Request Uri : sip:89274167850@172.16.1.9
1243935931 -> [makeInitialMotorRequest] nb available licenses=12 .
1243935931 -> [makeInitialMotorRequest] TCP added.
1243935931 -> [CGWMngData::isMainServer] CPU is MAIN.
1243935931 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [TCP]) (BUFF LEN = 906)
----------------------utf8-----------------------
INVITE sip:89274167850@172.16.1.9;transport=TCP SIP/2.0
Supported: replaces, timer,100rel
User-Agent: ABS GW v5.1.0
Session-Expires: 1800;Refresher=uac
Min-SE: 900
P-Asserted-Identity: "7406" <sip:7406@172.16.1.3>
Content-Type: application/sdp
To: sip:89274167850@172.16.1.9
From: "7406" <sip:7406@172.16.1.3>;tag=f9b87c479538a4c0b90959291a1a5632
Contact: <sip:172.16.1.3;transport=TCP>
Call-ID: efed0e8633ab512c3f3d28af6304eff4@172.16.1.3
CSeq: 1231165825 INVITE
Via: SIP/2.0/TCP 172.16.1.3;branch=z9hG4bKcc4958bd1ff5071696e684e677823df3
Max-Forwards: 70
Content-Length: 313

v=0
o=OXE 1243935931 1243935931 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.16
t=0 0
m=audio 32514 RTP/AVP 4 8 0 97
a=sendrecv
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1243935935 -> ALARM: 4 [CMessage::send] sip_sendMessage FAILED => HSS ERROR Send failed
1243935935 -> ALARM: 2104 [CTransInitialState::createRequest] CRequest::send FAILED => Terminated State
1243935935 -> [makeInitialMotorRequest] SECOND TIME meake generic stoped
1243935935 -> [makeInitialMotorRequest] makeGenericRequest error.
1243935935 -> [emitEventToMonitel] content size (lginfo): 648
1243935935 -> [emitEventToMonitel] CAC = 0 eqtCAC = -1
1243935935 -> [emitEventToMonitel] CAC = 0
1243935935 -> [emitEventToMonitel] Event sent on eqt : 1592
1243935935 -> [display_ipc_out] ------------ Begin ---------------
1243935935 -> GLOBAL FAILURE
1243935935 -> xx : 4
1243935935 -> RELATIVE REQUEST : INVITE
1243935935 -> [display_ipc_out] ------------- End ----------------
1243935935 -> ALARM: [receiveInviteEvent] Call: eqt: 1592 INITIAL_STATE failed to emit an Invite message.
1243935935 -> [onIncomingEvent] CallID must be inserted.
1243935935 -> [onIncomingEvent] CallID inserted.
1243935935 -> [ipc_thread] IPC Thread : Ipc reception.
1243935935 -> [ipc_thread] IPC Thread : TCL thread signaled.
1243935935 -> [exec_ipc] in.
1243935935 -> [display_ipc_in] ------------ Begin ---------------
1243935935 -> neqt : 1592
1243935935 -> SIP EQT RELEASED
1243935935 -> [display_ipc_in] ------------- End ----------------
1243935935 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1592.
1243935935 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 1592 has released its equipment.
1243935935 -> [CMotorCall::unRegister] Remove eqt : 1592 from the map.
1243935935 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1592 erased.
1243935935 -> [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
1243935935 -> [CMotorCallManager::onIncomingEvent] call deleted.
1243935935 -> [CMotorCall::~CMotorCall] Call is being destructed.



Редактировано 3 раз. Последний раз 02.06.2009 18:53 пользователем error.

Чем снимал трассировку (для самообразования)?
Не мог бы тоже сделать сниффером?
Входящие проходят? - это позволит судить о том, что SIP абоненты зарегистрировались на станции.

ну чем еще можно снимать трасировку SIP-ов как не traced
надыбал в доке еще tcpdump

когда то скачал вот такую штуку для анализа SIPa но в бою еще не приходилось юзать http://www.sipworkbench.com/

Пожалуйста приведи полностью команду для снятия трассировки SIP.
Я все "грехи" определяю сниффером, что для OXO, что для Enterprise.

команда "traced" без параметров

исходящий звонок по СИП типа пошел т.е. до шлюза где сип-транки есть добежал, вижу там строку в логе звонков, а на аппарате 4038 слышу фразу "доступ к услуге не разрещен"

motortrace 3
traced

а лучше wireshark, там и анализатор протокола есть

++++++++++++++++++
Anything's possible. Keep thinking.

поднял ОХЕ до f5.401.36.d

выпокал трассировку из sip-gateway

интересна страла срока вот эта - audio_codec: pt=97;ms=30;vad=0;rxtx=2;smap=telephone-event/8000
путем стуча в припыжку в шаманский бубен выяснилось что под pt=97 маркируется g711, в железке sip-gateway g711a маркируется под pt=8, ну соответственно g711u под pt=0

интересно стало - маркировка g711 под pt=97 в SIP действительно соответствует правде или же это такой прикол??????


------------------------------------------------------------------------

SETUP
call_type: normalcallin
ani: 7407;pr_alwd;scrn_netw
presentation: 7407;pr_alwd;scrn_netw
dnis: 89276000000
src_sign_addr: 172.16.1.3:65471
audio_codec: pt=4;ms=30;vad=0;rxtx=2;smap=G723/8000
audio_codec: pt=97;ms=30;vad=0;rxtx=2;smap=telephone-event/8000
audio_addr: 172.16.1.4:32520
a_sip: user=255
nat: no
b_sip: user=255

13:32:18,012590 16383 82 voipidle00
13:32:18,013169 16383 82 cctl_rxSETUP codec 4 rtpmap
13:32:18,013437 16383 82 cctl_rxSETUP codec 97 rtpmap
13:32:18,013689 16383 82 cctl_rxSETUP fast_start 1
13:32:18,014003 16383 82 voipidle00 Rx SETUP ani '7407' dnis '89276000000' -> Tx SETUP_ACK
13:32:18,014296 16383 82 SendMessISDN sursa 82 dest 65535 tip 254 len 24 proto 4
13:32:18,014542 16383 82 SendMessISDN proto SIP -> SendMes2SIP()
13:32:18,014938 16383 82 SendMess2SIP src 82 dst 65535 tip 254 len 24 proto 4
13:32:18,015221 16383 82 SendMess2SIP SendMesPBX2SIP(dest 263)
13:32:18,015519 16383 82 SendMesPBX2SIP Tx src 82 dst 263 len 24 pid_ua 1...

SETUP_ACK
sid: 2ab920e4

13:32:18,016026 16383 82 SendMesPBX2SIP Tx to dest 263
13:32:18,017097 16383 82 LiveMonitorSetup
13:32:18,017387 16383 82 in_check_access_1
13:32:18,017627 16383 82 in_check_access_1 NR_DIRIN_PBX 4000
13:32:18,017862 16383 82 in_check_access_1 0 ip1 ac100103 mask ffffffff ip2 ac100104
13:32:18,018226 16383 82 in_check_access_1 1 ip1 ac100103 mask ffffffff ip2 ac100103
13:32:18,018471 16383 82 in_check_access_1 digits 89276000000 prg_diripin[1].digits
13:32:18,018767 16383 82 in_check_access_1 Found diripin SIP, 0 simult calls from 172.16.1.3/32 proxy 0 transcoding=0 ssw_calls 0 max_ssw_calls 100000
13:32:18,019065 16383 82 CheckCallIn class 'SIP' nrcallin 1 maxspeachin 0 speachin 0 maxcostin 0.000000 costin 0.000000, ssw_calls 1
13:32:18,019424 16383 82 CountSysCallIn IN SYSTEM medium_cps 0.000000 max_cps 0 MAX 0
13:32:18,019832 16383 82 CountSysCallIn IN SYSTEM sec 1244457138 calls in this sec 1
13:32:18,020130 16383 82 CountCallInOn IN dir nr 8 name SIP medium_cps 0.000000 max_cps 0 MAX 0 calls IN 1
13:32:18,020434 16383 82 CountCallInOn IN dir nr 8 name SIP sec 1244457138 calls in this sec 1
13:32:18,020727 16383 82 voipidle00 have all digits fast_start is 1
13:32:18,020986 16383 82 SendMessISDN sursa 82 dest 65535 tip 130 len 1 proto 4
13:32:18,021236 16383 82 SendMessISDN proto SIP -> SendMes2SIP()
13:32:18,021596 16383 82 SendMess2SIP src 82 dst 65535 tip 130 len 1 proto 4
13:32:18,021968 16383 82 SendMessISDN sursa 82 dest 65535 tip 254 len 36 proto 4
13:32:18,022253 16383 82 SendMessISDN proto SIP -> SendMes2SIP()
13:32:18,022509 16383 82 SendMess2SIP src 82 dst 65535 tip 254 len 36 proto 4
13:32:18,022773 16383 82 SendMess2SIP SendMesPBX2SIP(dest 263)
13:32:18,023186 16383 82 SendMesPBX2SIP Tx src 82 dst 263 len 36 pid_ua 1...

ACM
bci: isup_used_alltheway;charge

13:32:18,023729 16383 82 SendMesPBX2SIP Tx to dest 263
13:32:18,024011 16383 82 voipidle00 Instal(dialdigits, 30000)
13:32:18,024271 16383 82 Set_Timer tmr 0 val 30000
13:32:18,025113 16383 82 dialdigits ident '7407' number '89276000000' id_tradu '7407' nr_tradu '89276000000' route 0x0000
13:32:18,025427 16383 82 dialdigits M_RUN
13:32:18,025684 16383 82 dialdig_run ta->rtpproxy 0
13:32:18,025941 16383 82 dialdig_run pupi_db_id len 0, number 89276000000
13:32:18,027614 16383 82 GetDest GetDest rn 0
13:32:18,027894 16383 82 GetDest classid 8
13:32:18,028252 16383 82 GetDest search_bin_tradu class 8 alldigit 1
13:32:18,028495 16383 82 search_nr_jonbin digit 8 first_poz 0 nr_poz 1
13:32:18,028758 16383 82 GetDest poz 7fff len 0 match 0 noa 3
13:32:18,029003 16383 82 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
13:32:18,029237 16383 82 GetDest tryf poz 32767 len 0 match 0 noa 3
13:32:18,029473 16383 82 GetDest try with default route
13:32:18,029867 16383 82 GetDest GetDest rn 0
13:32:18,030114 16383 82 GetDest classid 65535
13:32:18,030388 16383 82 GetDest search_bin_tradu class 65535 alldigit 1
13:32:18,030633 16383 82 search_nr_jonbin digit 8 first_poz 0 nr_poz 1
13:32:18,030880 16383 82 GetDest poz 9000 len 1 match 1 noa 3
13:32:18,031127 16383 82 GetDest route 0x9000 rlen 1 rmatch 1 r 0x4061a730 nr 0
13:32:18,031496 16383 82 GetDest find 1 routes
13:32:18,031740 16383 82 GetDest find maxpoz 0
13:32:18,031989 16383 82 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
13:32:18,032225 16383 82 GetDest find all f tryf poz 32767 len 1 match 1 noa 3
13:32:18,032497 16383 82 GetDest find ok routes 1
13:32:18,032740 16383 82 GetDest DIRACTION poz 0x9000 idx 0 dest 0x1 type_dir 0x1
13:32:18,033116 16383 82 play_announcement Missing route play_file -> return
13:32:18,033391 16383 82 dialdig_run ident '7407' number '89276000000' id_tradu '7407' nr_tradu '89276000000' route 0x9000
13:32:18,033678 16383 82 dialdig_run tax from tradu is 0 bpf_r_in 0 connection time is 0 min
13:32:18,033945 16383 82 get_maxproxy_in proxyin 0
13:32:18,034194 16383 82 dialdig_recmes_switch DIRACTION
13:32:18,034427 16383 82 dialdig_recmes_switch nrtrytodir 0
13:32:18,034809 16383 82 try_play_time ppu is NULL -> return 0
13:32:18,035073 16383 82 dialdig_recmes_switch NewCallJon(nr_out '89276000000', id_out '7407')
13:32:18,035335 16383 82 NewCallJon route 36864
13:32:18,035569 16383 82 NewCallJon GetRoute 1
13:32:18,035802 16383 82 GetRoute route 0 mode 0 data 0
13:32:18,036068 16383 82 GetRoute route 0 mode 0 data 0 dir GSM ASR 0.000000
13:32:18,037242 16383 82 GetRoute return route 9000 ASR max 0.000000 on dir 1
13:32:18,037532 16383 82 NewCallJon global_route 0 fork_alg 0
13:32:18,037771 16383 82 NewCallJon route 9000 poz 0
13:32:18,038134 16383 82 NewCallJon dir GSM proto UNKNOWN nr_out '89276000000' id_out '7407'
13:32:18,038406 16383 82 NewCallJon dir 1 translate_out 0
13:32:18,038661 16383 82 try_alloc_transcoding
13:32:18,038898 16383 82 try_alloc_transcoding 3
13:32:18,039147 16383 82 CallJonDir IP to TDM
13:32:18,039458 16383 82 CallJonDir find portB 16 ch 0 0
13:32:18,039849 16383 82 cctl_allocRTPportBIGSW find transcoding port 65535
13:32:18,040109 16383 82 cctl_allocRTPportBIGSW ERROR find transcoding port
13:32:18,040361 16383 82 CallJonDir CONGESTION dir 'GSM' port A 65535
13:32:18,040602 16383 82 NewCallJon global_b_pid 0

13:32:18,040845 16383 82 NewCallJon ret 38 nrtrytodir 1 param 0
13:32:18,041098 16383 82 EndNewCallJon ret 38
13:32:18,041451 16383 82 InformPortB port 65535
13:32:18,041702 16383 82 InformPortB port 65535
13:32:18,041953 16383 82 EndNewCallJon ret 38 find PORTB 16
13:32:18,042198 16383 82 update_prepaid_credit ppu (nil), OD_ppu (nil), RB_ppu (nil)
13:32:18,042748 16383 82 get_sign_media aIP 0.0.0.0:65535
13:32:18,043202 16383 82 GetLocalRtpIpPort
13:32:18,043422 16383 82 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
13:32:18,043709 16383 82 get_pattern_addr IP from remote media 172.16.1.4
13:32:18,043961 16383 82 GetLocalRtpIpPort RTPP no, Tx aIP 0.0.0.0:65535
13:32:18,044228 16383 82 get_sign_media sourceIP 172.16.1.3:65471 sourceRTP 172.16.1.4:32520 destIP 172.16.1.9:0 destRTP 0.0.0.0:65535
13:32:18,044585 16383 82 do_tax tip 'A' duration 0 finalize 'CONGESTION' sid 2ab920e4
13:32:18,044967 16383 82 do_tax SIGN_s 172.16.1.3:65471 RTP_s 172.16.1.4:32520 SIGN_d 172.16.1.9:0 RTP_d 0.0.0.0:65535
13:32:18,045811 16383 82 ReleaseMode CONGESTION 34
13:32:18,046095 16383 82 SendMes WARN dest pid 65535 is higher than nrproc 354 -> msg not sent
13:32:18,047432 16383 82 TxDisactPort
13:32:18,047675 16383 82 TxDisactPort code 34
13:32:18,047902 16383 82 TxDisactPort SendRESTIMESLOT, Tx M_PRESET_CH cause 34, Instal(cctl_waitdisact, 6000)
13:32:18,048264 16383 82 SendMessISDN sursa 82 dest 65535 tip 134 len 1 proto 4
13:32:18,048517 16383 82 SendMessISDN proto SIP -> SendMes2SIP()
13:32:18,048774 16383 82 SendMess2SIP src 82 dst 65535 tip 134 len 1 proto 4
13:32:18,049097 16383 82 SendMess2SIP nex 82 mod 0 mcpsin 0.000000 curcpsin 1
13:32:18,049390 16383 82 SendMessISDN sursa 82 dest 65535 tip 254 len 18 proto 4
13:32:18,049780 16383 82 SendMessISDN proto SIP -> SendMes2SIP()
13:32:18,050034 16383 82 SendMess2SIP src 82 dst 65535 tip 254 len 18 proto 4
13:32:18,050308 16383 82 SendMess2SIP SendMesPBX2SIP(dest 263)
13:32:18,050561 16383 82 SendMesPBX2SIP Tx src 82 dst 263 len 18 pid_ua 1...

RELEASE
cause: 34

народ!!! помогайте
как можно посмотреть траффик обмена между платой intip и sip-gateway?
пробывал через tcpdump ничего не получилось

самый правильный тул - wireshark. только надо иметь настоящий старинный HUB - 10 mbit half-duplex который транслирует пакеты на все порты (современные мелкие псевдохабы все-таки изолируют порты недопуская коллизий). включаете в этот хаб пачкорды от int-ip, sip gateway, и свой компьютер с wireshark. включаете перехват пакетов на своей карте ethernet. делаете звонок, сохраняете все в файл или сразу в wireshark кликаете в кнопку statistic, в выпавшем меню - voip calls. в таблице выбираете свой звонок - смотрите в графике или слушаете звук или видите, почему звонок не идет...

++++++++++++++++++
Anything's possible. Keep thinking.

не уверен, но по-моему tcpdump на плате int-ip запустить нельзя, надо проверить, сейчас в отпуске не на чем :)
если сработает, то по идее tcpdump -w sip.cap далее по ftp этот файл забрать со станции и его можно загрузить для анализа в тотже wireshark.
wireshark хорош еще и тем, что этот тул понятен должен быть и для хозяев sip gateway (если это не вы)

++++++++++++++++++
Anything's possible. Keep thinking.

как говорится утро вечера мудренее

потыкался wireshark и не чего путного в траффике не увидел, вот сижу и думаю с какого бадуна должен быть траффик по rtp портам когда атс при исходящем звонке выдает rtp порт к примеру 32560, а sip-gateway выдает 32524 или я что-то начинаю путать?????

А можно всетаки чуть подробнее про проблему, где-то в середине переписки нашел
"исходящий звонок по СИП типа пошел т.е. до шлюза где сип-транки есть добежал, вижу там строку в логе звонков, а на аппарате 4038 слышу фразу "доступ к услуге не разрещен"".
Нет исходящей и входящей, только исходящей, или исходящей, но только с IPTouch.

Всякие мультиалгоритмы для компрессии включены?
Звоните через ARS или как?
TG ISDN или ABC_F

А в трассировке фразы типа
"sourceIP 172.16.1.3:65471 sourceRTP 172.16.1.4:32520 destIP 172.16.1.9:0 destRTP 0.0.0.0:65535"
destination RTP какой то странный

sip TG прописана как isdn
звонок идет через ars
до вчерашнего дня выудить инфу как настроить sip-gateway практически не удавалось пока не пригрозили что откажемся от gateway, в это их спецы взялись за настройку этого самого gateway. честно говоря настройка gateway очень паразила меня т.е. звонок пришедший на voip плату gateway-я сперва пробегает через виртуальную Isdn плату и затем уж идет на нужный порт

фрагмент из лога
13:32:18,039849 16383 82 cctl_allocRTPportBIGSW find transcoding port 65535
13:32:18,040109 16383 82 cctl_allocRTPportBIGSW ERROR find transcoding port
вот как раз 2-ая строчка должна ссылаться на виртуальный isdn порт

в данный момент звонок проходит но обмена голосового трафика нет

--------------------------------
трасса с атс

1245738753 -> [ipc_thread] IPC Thread : Ipc reception.
1245738753 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738753 -> [exec_ipc] in.
1245738753 -> [display_ipc_in] ------------ Begin ---------------
1245738753 -> neqt : 716
1245738753 -> INVITE
1245738753 -> REQUEST URI : <> 5338813@172.16.1.9:5060 ; user=phone
1245738753 -> FROM : <7407> 7407@172.16.1.3:5060 ; user=name
1245738753 -> TO : <> 5338813@172.16.1.9:5060 ; user=phone
1245738753 -> CAC : 0
1245738753 -> neqt CAC : -1
1245738753 -> CLIR : 0
1245738753 -> SDP :
1245738753 -> ADDRESS : 172.16.1.4 :32520
1245738753 -> ALGOS :
1245738753 -> PCMA
1245738753 -> PCMU
1245738753 -> G729
1245738753 -> 97
1245738753 -> DIRECTION : SEND & RECEIVE
1245738753 -> N_GW_EXT : 0
1245738753 -> [display_ipc_in] ------------- End ----------------
1245738753 -> [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 716.
1245738753 -> [CMotorCallManager::onIncomingEvent] New Call created.
1245738753 -> [CMotorCallManager::callFactory].
1245738753 -> [CMotorCallManager::callFactory]=83aad10.
1245738753 -> [CMotorCallManager::onIncomingEvent] eqt=716.
1245738753 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 716 inserted.
1245738753 -> [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
1245738753 -> [onIncomingEvent] INVITE arrived.
1245738753 -> [receiveInviteEvent] Call: eqt: 716 INITIAL_STATE received an event.
1245738753 -> 110e[CMotorCall::emitInviteMessage] begin.
1245738753 -> [isValidGwExt] ext gw 0 is valid
1245738753 -> [quoteString] => "7407"
1245738753 -> 110e[CMotorCall::inviteBuildAurlWithoutBelongingDomain] P-Asserted-Identity: "7407" <sip:7407@172.16.1.3>
1245738753 -> 110e[CMotorCall::emitInviteMessage] From: "7407" <sip:7407@172.16.1.3>
1245738753 -> 110e[CMotorCall::emitInviteMessage] reqUri :sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::inviteBuildSupportedHeader] Invite directed to external gw : 0
1245738753 -> 110e[CMotorCall::emitInviteMessage] Request Uri : sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::emitInviteMessage] To: <sip:5338813@172.16.1.9;user=phone>
1245738753 -> 110e[CMotorCall::emitInviteMessage] Contact: sip:172.16.1.3
1245738753 -> 110e[CMotorCall::makeRequestSdp] Begin sdp : v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=-
c=IN IP4 0.0.0.0
t=0 0
.
1245738753 -> 110e[CMotorCall::makeRequestSdp] Direction: 0.
1245738753 -> 110e[CMotorCall::makeResponseSdp] format 97
1245738753 -> 110e[CMotorCall::makeRequestSdp] Final sdp: v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.4
t=0 0
m=audio 32520 RTP/AVP 8 0 18 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=ptime:20
a=maxptime:40
a=rtpmap:97 telephone-event/8000
.
1245738753 -> 110e[CMotorCall::emitInviteMessage] The sdp build: .
1245738753 -> 110e[CMotorCall::new_determineTargetsForTheRequest] is not Responsible For This URI.
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb contacts=1.
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 5338813
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest]-->extGw=0
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] contact : sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb available licenses=99999 .
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest]-->pSipAddSpec->getHost()=172.16.1.9
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] user= 5338813
1245738753 -> [CGWMngData::isMainServer] CPU is MAIN.
1245738753 -> 110e [CCall::makeGenericRequest] INVITE
1245738753 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = ;)
1245738753 -> 110e [CCall::getDialog] Returning Initial Dialog Client
1245738753 -> 1515 [CDialog::createRequest]
1245738753 -> 1515 [CDialog::buildServicesForAllRequest]
1245738753 -> 1515 [CDialog::createInviteRequest]
1245738753 -> 211c [CTransaction::changeState] STATE CHANGED TO INITIAL
1245738753 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738753 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Terminated, currentState = Initial, reason = None]
1245738753 -> 22 [CRequest::CRequest] Creation of a request
1245738753 -> 22 [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738753 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 835)
----------------------utf8-----------------------
INVITE sip:5338813@172.16.1.9;user=phone SIP/2.0
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
P-Asserted-Identity: "7407" <sip:7407@172.16.1.3>
Content-Type: application/sdp
To: <sip:5338813@172.16.1.9;user=phone>
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Contact: sip:172.16.1.3
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Max-Forwards: 70
Content-Length: 314

v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.4
t=0 0
m=audio 32520 RTP/AVP 8 0 18 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=ptime:20
a=maxptime:40
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1245738753 -> 211c [CTransInitialState::createRequest] Transaction changes to Calling state
1245738753 -> 211c [CTransaction::changeState] STATE CHANGED TO CALLING
1245738753 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738753 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Initial, currentState = Calling, reason = Request creation]
1245738753 -> 211c [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1245738753 -> 211c [CTransaction::startTimer] Timer B is started (delay = 32000 ms)
1245738753 -> [---*--- SESSION-TIMER ---*---] New CSessionTimerContext from request (Client, UA)

1245738753 -> [---*--- SESSION-TIMER ---*---] Update CSessionTimerContext (refresh creation)

1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb contact=1.
1245738753 -> [CForkedBranch::CForkedBranch] context = 0x83aae04.
1245738753 -> 110e[CMotorCall::branchAllocation] built branch number=1.
1245738753 -> [onIncomingEvent] CallID must be inserted.
1245738753 -> [onIncomingEvent] CallID inserted.
1245738754 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------

1245738754 -> [CIOCom::receiveResponse] CResponse 2C ref=1
1245738754 -> [CDispatcher::onIncomingResponse]
1245738754 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738754 -> 102 [CCallManager::onIncomingResponse]
1245738754 -> 110e [CCall::receiveResponse] 100
1245738754 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738754 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738754 -> 110e [CCall::receiveResponse]One dialog found
1245738754 -> 1515 [CDialog::receiveResponse]
1245738754 -> 211c [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
1245738754 -> 211c [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1245738754 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738754 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Calling, currentState = Proceeding, reason = 1xx response reception]
1245738754 -> 211c [CTransaction::freeTimerToken] Timer A is freed
1245738754 -> 211c [CTransaction::freeTimerToken] Timer B is freed
1245738754 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738754 -> [receiveInformationalMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738756 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 141

v=0
o=root 386787 386787 IN IP4 172.16.1.9
s=Topex
c=IN IP4 172.16.1.9
t=0 0
m=audio 32518 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
-------------------------------------------------

1245738756 -> [CIOCom::receiveResponse] CResponse 2D ref=1
1245738756 -> [CDispatcher::onIncomingResponse]
1245738756 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738756 -> 102 [CCallManager::onIncomingResponse]
1245738756 -> 110e [CCall::receiveResponse] 183
1245738756 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738756 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738756 -> 110e [CCall::receiveResponse]One dialog found
1245738756 -> 1515 [CDialog::receiveResponse]
1245738756 -> 2C [~CResponse] resp(100) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738756 -> 1515 [CDialog::updateRouteSet] there are 0 record-route headers
1245738756 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738756 -> [receiveInformationalMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738756 -> [checkSdpValidity] Sdp contains 1 media description(s).
1245738756 -> [checkSdpValidity] Media 0 type 1 contains 1 formats.
1245738756 -> [checkSdpValidity] Format : 8.
1245738756 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1245738756 -> [convertSdpIntoTsdp] The audio media contains 1 format(s).
1245738756 -> [convertSdpIntoTsdp] Format 0 is 8.
1245738756 -> [convertSdpIntoTsdp] Get the direction.
1245738756 -> [convertSdpIntoTsdp] Direction is sendrecv.
1245738756 -> [convertSdpIntoTsdp] The connection is at session level.
1245738756 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.16.1.9.
1245738756 -> [convertIPStrIntoTuipv] 172.16.1.9 => 172.16.1.9
1245738756 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738756 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738756 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738756 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738756 -> [display_ipc_out] ------------ Begin ---------------
1245738756 -> INFORMATIONAL
1245738756 -> xx : 83
1245738756 -> RELATIVE REQUEST : INVITE
1245738756 -> SDP :
1245738756 -> ADDRESS : 172.16.1.9 :32518
1245738756 -> ALGOS :
1245738756 -> PCMA
1245738756 -> DIRECTION : SEND & RECEIVE
1245738756 -> [display_ipc_out] ------------- End ----------------
1245738760 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 141

v=0
o=root 529264 529264 IN IP4 172.16.1.9
s=Topex
c=IN IP4 172.16.1.9
t=0 0
m=audio 32518 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
-------------------------------------------------

1245738760 -> [CIOCom::receiveResponse] CResponse 2E ref=1
1245738760 -> [CDispatcher::onIncomingResponse]
1245738760 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738760 -> 102 [CCallManager::onIncomingResponse]
1245738760 -> 110e [CCall::receiveResponse] 200
1245738760 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738760 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738760 -> 110e [CCall::receiveResponse]One dialog found
1245738760 -> 1515 [CDialog::receiveResponse]
1245738760 -> 1515 [CDialog::receiveResponse] create a CONFIRMED dialog
1245738760 -> 1516 [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
1245738760 -> 1516 [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
1245738760 -> 211d [CTransaction::CTransaction] Transaction is cloned in 4 state
1245738760 -> 1516 [CDialog::startDialogTTL]
1245738760 -> 1516 [CDialog::receiveResponse]
1245738760 -> 211d [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
1245738760 -> 211d [CTransaction::changeState] STATE CHANGED TO COMPLETED
1245738760 -> 211d [CTransaction::changeState] notifying the parent dialog
1245738760 -> 1516 [CDialog::onTransactionState(pTrans = 211d, previousState = Proceeding, currentState = Completed, reason = Final resp reception]
1245738760 -> 211d [CTransaction::startTimer] Timer D is started (delay = 32361 ms)
1245738760 -> [---*--- SESSION-TIMER ---*---] New CSessionTimerContext from response (Client, UA)

1245738760 -> [---*--- SESSION-TIMER ---*---] Update CSessionTimerContext (response refresh reception)

1245738760 -> [---*--- SESSION-TIMER ---*---] No refresh

1245738760 -> 1516 [CDialog::updateRouteSet] there are 0 record-route headers
1245738760 -> 110e [CCall::insertInDialogMap] added dialog #1516 with the key 86dc8343e89f40424027da4702cad252;top7eaca361

1245738760 -> 1515 [CDialog::updateRouteSet] there are 0 record-route headers
1245738760 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738760 -> [CForkedBranch::setFinalResponse] pResponse=0x839c230.
1245738760 -> [CForkedBranch::setFinalResponse] response=200.
1245738760 -> 110e[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
1245738760 -> 110e[CMotorCall::chooseBestResponse] pForkedBranch=0x839e688 iResponseCode=2000.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> 110e[CMotorCall::chooseBestResponse] last iResponseCode=200.
1245738760 -> [CForkedBranch::setFinalResponse] pResponse=0x839c230.
1245738760 -> 110e[CMotorCall::chooseBestResponse] next branch.
1245738760 -> 110e[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1245738760 -> [receiveSuccessfulMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738760 -> [checkSdpValidity] Sdp contains 1 media description(s).
1245738760 -> [checkSdpValidity] Media 0 type 1 contains 1 formats.
1245738760 -> [checkSdpValidity] Format : 8.
1245738760 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1245738760 -> [convertSdpIntoTsdp] default T38 config.
1245738760 -> [convertSdpIntoTsdp] The audio media contains 1 format(s).
1245738760 -> [convertSdpIntoTsdp] Format 0 is 8.
1245738760 -> [convertSdpIntoTsdp] Get the direction.
1245738760 -> [convertSdpIntoTsdp] Direction is sendrecv.
1245738760 -> [convertSdpIntoTsdp] The connection is at session level.
1245738760 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.16.1.9.
1245738760 -> [convertIPStrIntoTuipv] 172.16.1.9 => 172.16.1.9
1245738760 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738760 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738760 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738760 -> [ipc_thread] IPC Thread : Ipc reception.
1245738760 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738760 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738760 -> [display_ipc_out] ------------ Begin ---------------
1245738760 -> SUCCESSFUL
1245738760 -> xx : 0
1245738760 -> RELATIVE REQUEST : INVITE
1245738760 -> CLIR : 0
1245738760 -> COLP : 0
1245738760 -> SDP :
1245738760 -> ADDRESS : 172.16.1.9 :32518
1245738760 -> ALGOS :
1245738760 -> PCMA
1245738760 -> DIRECTION : SEND & RECEIVE
1245738760 -> [display_ipc_out] ------------- End ----------------
1245738760 -> 110e[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
1245738760 -> 110e[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [exec_ipc] in.
1245738760 -> [display_ipc_in] ------------ Begin ---------------
1245738760 -> neqt : 716
1245738760 -> ACK
1245738760 -> [display_ipc_in] ------------- End ----------------
1245738760 -> [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 716.
1245738760 -> [CMotorCallManager::onIncomingEvent] state = ACCEPTED_STATE.
1245738760 -> [onIncomingEvent] ACK arrived.
1245738760 -> [receiveAckEvent] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 ACCEPTED_STATE received an event.
1245738760 -> 110e[CMotorCall::emitAckMessage] begin.
1245738760 -> 110e[CMotorCall::emitAckMessage] SDP ok.
1245738760 -> 110e [CCall::makeGenericRequest] ACK
1245738760 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738760 -> 1516 [CDialog::createRequest]
1245738760 -> 1516 [CDialog::buildServicesForAllRequest]
1245738760 -> 1516 [CDialog::createAckRequest]
1245738760 -> 1516 [CDialog::createAckRequest], previous trans 211d is found
1245738760 -> 1516 [CDialog::createAckRequest], previous trans 211d is client
1245738760 -> 23 [CRequest::CRequest] Creation of a request
1245738760 -> 1516 [CDialog::buildServicesForAck]
1245738760 -> 23 [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738760 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 413)
----------------------utf8-----------------------
ACK sip:5338813@172.16.1.9:5060 SIP/2.0
Contact: sip:172.16.1.3
User-Agent: ABS GW v5.1.0
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 ACK
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK7e9a8303002a2dd7b73012f48dc664fc
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
1245738760 -> 211d [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738760 -> 211d [CTransaction::freeTimerToken] Timer D is freed
1245738760 -> 211d [CTransaction::changeState] no transaction state
1245738760 -> 1516 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1245738760 -> [CTransaction::notifyDialog]
1245738760 -> [CTransaction::notifyDialog] notify parent dialog
1245738760 -> 1516 [CDialog::onTransactionState(pTrans = 211d, previousState = null, currentState = Terminated, reason = Request creation]
1245738769 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
BYE sip:172.16.1.3 SIP/2.0
Via: SIP/2.0/UDP 172.16.1.9:5060;branch=z9hG4bK72d9de66
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 1 BYE
From: <sip:5338813@172.16.1.9:5060>;tag=top7eaca361
To: <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Reason: Q.850 ;cause=16 ;text="Normal call clearing"
Supported: timer,replaces
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------

1245738769 -> 24 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1245738769 -> [CIOCom::receiveRequest] Received CRequest 24 ref=1
1245738769 -> [CDispatcher::onIncomingRequest()]
1245738769 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1245738769 -> 102 [CCallManager::onIncomingRequest()]
1245738769 -> 110e [CCall::receiveRequest] BYE
1245738769 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738769 -> 1516 [CDialog::receiveRequest]
1245738769 -> 1516 [CDialog::receiveBasicRequest]
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO INITIAL
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Terminated, currentState = Initial, reason = None]
1245738769 -> 211e [CTransInitialState::receiveRequest] Transaction changes to Trying state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO TRYING
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Initial, currentState = Trying, reason = Request reception]
1245738769 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1245738769 -> 110e [CCall::checkAuthentication] BYE
1245738769 -> 110e[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1245738769 -> 110e[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1245738769 -> 110e[CMotorCall::toGatewayOrProxy] request for proxydomain=172.16.1.3.
1245738769 -> [isUserRegistred] result=-1.
1245738769 -> 110e[CMotorCall::onReceiveRequest] request for gateway.
1245738769 -> 110e[CMotorCall::onReceiveRequest] state = COMPLETED_STATE.
1245738769 -> [receiveByeMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 COMPLETED_STATE received a message.
1245738769 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738769 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738769 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738769 -> [ipc_thread] IPC Thread : Ipc reception.
1245738769 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738769 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738769 -> [display_ipc_out] ------------ Begin ---------------
1245738769 -> BYE
1245738769 -> [display_ipc_out] ------------- End ----------------
1245738769 -> [exec_ipc] in.
1245738769 -> [display_ipc_in] ------------ Begin ---------------
1245738769 -> neqt : 716
1245738769 -> SUCCESSFUL
1245738769 -> xx : 0
1245738769 -> RELATIVE REQUEST : BYE
1245738769 -> CLIR : 0
1245738769 -> COLP : 0
1245738769 -> [display_ipc_in] ------------- End ----------------
1245738769 -> [CMotorCallManager::onIncomingEvent] an event 10765 arrived on the eqt 716.
1245738769 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1245738769 -> [onIncomingEvent] SUCCESSFUL arrived.
1245738769 -> [receiveSuccessfulEvent] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 TERMINATED_STATE received an event.
1245738769 -> 110e [CCall::findFirstServerTrans]
1245738769 -> 1516 [CCall::findFirstServerTrans]
1245738769 -> 1516 [CCallLeg::findFirstOpenServerTrans] Current Request in transactionMapByBranch
1245738769 -> 110e [CCall::makeGenericResponse] 200
1245738769 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738769 -> 1516 [CDialog::createResponse] 200
1245738769 -> 211e [CTransTryingState::createResponse] CResponse 2F ref=1
1245738769 -> 2F [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738769 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 401)
----------------------utf8-----------------------
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, SUBSCRIBE, OPTIONS
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
To: <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
From: <sip:5338813@172.16.1.9:5060>;tag=top7eaca361
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 1 BYE
Via: SIP/2.0/UDP 172.16.1.9:5060;branch=z9hG4bK72d9de66
Content-Length: 0

-------------------------------------------------
1245738769 -> 211e [CTransTryingState::createResponse] Final : Transaction changes to Completed state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO COMPLETED
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Trying, currentState = Completed, reason = Final resp creation]
1245738769 -> 211e [CTransaction::startTimer] Timer J is started (delay = 32000 ms)
1245738769 -> 1516 [CDialog::updateRouteSet] there are 0 record-route headers
1245738769 -> [ipc_thread] IPC Thread : Ipc reception.
1245738769 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738769 -> [exec_ipc] in.
1245738769 -> [display_ipc_in] ------------ Begin ---------------
1245738769 -> neqt : 716
1245738769 -> SIP EQT RELEASED
1245738769 -> [display_ipc_in] ------------- End ----------------
1245738769 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 716.
1245738769 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 716 has released its equipment.
1245738769 -> 110e[CMotorCall::unRegister] Remove eqt : 716 from the map.
1245738769 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 716 erased.
1245738769 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1245738769 -> [CMotorCallManager::onIncomingEvent] killSession.
1245738769 -> 110e [CCall::killSession]
1245738769 -> 211c [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738769 -> 211c [CTransaction::changeState] no transaction state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738769 -> 211e [CTransaction::freeTimerToken] Timer J is freed
1245738769 -> 211e [CTransaction::changeState] no transaction state
1245738769 -> 110e [CCall::onKill Session Call manager close is called]
1245738769 -> 110e [CCall::killSession] notifying the call manager
1245738769 -> 102 [CCallManager::onCallClosed]
1245738769 -> 102 [CCallManager::onCallClosed()] removing from the maps
1245738769 -> [CMotorCallManager::onNotifyCallClosed] Call : c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 is closed.
1245738769 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1245738769 -> [CCall::onKill] set callmanager to null
1245738769 -> [CTransaction::notifyDialog]
1245738769 -> [CTransaction::notifyDialog] notify parent dialog
1245738769 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = null, currentState = Terminated, reason = None]
1245738769 -> 1515 [CDialog::onTransactionState] dialog is terminated
1245738769 -> 1515 [CDialog::onTransactionState] onDialogClosed
1245738769 -> 110e [CCall::onDialogClosed]
1245738769 -> 110e [CCall::onDialogClosed] not only initial *********
1245738769 -> 110e [CCall::onDialogClosed]: the dialog 1516 is NOT closed
1245738769 -> 2D [~CResponse] resp(183) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> [CTransaction::notifyDialog]
1245738769 -> [CTransaction::notifyDialog] notify parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = null, currentState = Terminated, reason = None]
1245738769 -> 110e [CCall::onDialogClosed]
1245738769 -> 110e [CCall::onDialogClosed] not only initial *********
1245738769 -> 110e [CCall::onDialogClosed]: the dialog 1516 is closed
1245738769 -> 110e [CCall::onDialogClosed] All the dialogs in the Call are closed
1245738769 -> 102 [CCallManager::removeGarbage] at index i = 0
1245738769 -> 110e[CMotorCall::~CMotorCall] Call : c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 is being destructed.
1245738769 -> 110e [CCall::~CCall]
1245738769 -> 110e [CCall::removeGarbage]
1245738769 -> 1516 [CDialog::~CDialog]
1245738769 -> 1515 [CDialog::~CDialog]
1245738769 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1245738769 -> 23 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 ACK
1245738769 -> 2E [~CResponse] resp(200) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> 22 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> 2F [~CResponse] resp(200) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 1 BYE
1245738769 -> 24 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 1 BYE

sip TG прописана как isdn
звонок идет через ars
до вчерашнего дня выудить инфу как настроить sip-gateway практически не удавалось пока не пригрозили что откажемся от gateway, в это их спецы взялись за настройку этого самого gateway. честно говоря настройка gateway очень паразила меня т.е. звонок пришедший на voip плату gateway-я сперва пробегает через виртуальную Isdn плату и затем уж идет на нужный порт

фрагмент из лога
13:32:18,039849 16383 82 cctl_allocRTPportBIGSW find transcoding port 65535
13:32:18,040109 16383 82 cctl_allocRTPportBIGSW ERROR find transcoding port
вот как раз 2-ая строчка должна ссылаться на виртуальный isdn порт

в данный момент звонок проходит но обмена голосового трафика нет

--------------------------------
трасса с атс

1245738753 -> [ipc_thread] IPC Thread : Ipc reception.
1245738753 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738753 -> [exec_ipc] in.
1245738753 -> [display_ipc_in] ------------ Begin ---------------
1245738753 -> neqt : 716
1245738753 -> INVITE
1245738753 -> REQUEST URI : <> 5338813@172.16.1.9:5060 ; user=phone
1245738753 -> FROM : <7407> 7407@172.16.1.3:5060 ; user=name
1245738753 -> TO : <> 5338813@172.16.1.9:5060 ; user=phone
1245738753 -> CAC : 0
1245738753 -> neqt CAC : -1
1245738753 -> CLIR : 0
1245738753 -> SDP :
1245738753 -> ADDRESS : 172.16.1.4 :32520
1245738753 -> ALGOS :
1245738753 -> PCMA
1245738753 -> PCMU
1245738753 -> G729
1245738753 -> 97
1245738753 -> DIRECTION : SEND & RECEIVE
1245738753 -> N_GW_EXT : 0
1245738753 -> [display_ipc_in] ------------- End ----------------
1245738753 -> [CMotorCallManager::onIncomingEvent] an event 10752 arrived on the eqt 716.
1245738753 -> [CMotorCallManager::onIncomingEvent] New Call created.
1245738753 -> [CMotorCallManager::callFactory].
1245738753 -> [CMotorCallManager::callFactory]=83aad10.
1245738753 -> [CMotorCallManager::onIncomingEvent] eqt=716.
1245738753 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 716 inserted.
1245738753 -> [CMotorCallManager::onIncomingEvent] state = INITIAL_STATE.
1245738753 -> [onIncomingEvent] INVITE arrived.
1245738753 -> [receiveInviteEvent] Call: eqt: 716 INITIAL_STATE received an event.
1245738753 -> 110e[CMotorCall::emitInviteMessage] begin.
1245738753 -> [isValidGwExt] ext gw 0 is valid
1245738753 -> [quoteString] => "7407"
1245738753 -> 110e[CMotorCall::inviteBuildAurlWithoutBelongingDomain] P-Asserted-Identity: "7407" <sip:7407@172.16.1.3>
1245738753 -> 110e[CMotorCall::emitInviteMessage] From: "7407" <sip:7407@172.16.1.3>
1245738753 -> 110e[CMotorCall::emitInviteMessage] reqUri :sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::inviteBuildSupportedHeader] Invite directed to external gw : 0
1245738753 -> 110e[CMotorCall::emitInviteMessage] Request Uri : sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::emitInviteMessage] To: <sip:5338813@172.16.1.9;user=phone>
1245738753 -> 110e[CMotorCall::emitInviteMessage] Contact: sip:172.16.1.3
1245738753 -> 110e[CMotorCall::makeRequestSdp] Begin sdp : v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=-
c=IN IP4 0.0.0.0
t=0 0
.
1245738753 -> 110e[CMotorCall::makeRequestSdp] Direction: 0.
1245738753 -> 110e[CMotorCall::makeResponseSdp] format 97
1245738753 -> 110e[CMotorCall::makeRequestSdp] Final sdp: v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.4
t=0 0
m=audio 32520 RTP/AVP 8 0 18 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=ptime:20
a=maxptime:40
a=rtpmap:97 telephone-event/8000
.
1245738753 -> 110e[CMotorCall::emitInviteMessage] The sdp build: .
1245738753 -> 110e[CMotorCall::new_determineTargetsForTheRequest] is not Responsible For This URI.
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb contacts=1.
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] *pcszNameToFind : 5338813
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest]-->extGw=0
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] contact : sip:5338813@172.16.1.9;user=phone
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb available licenses=99999 .
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] req_Uri_OK=0
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest]-->pSipAddSpec->getHost()=172.16.1.9
1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] user= 5338813
1245738753 -> [CGWMngData::isMainServer] CPU is MAIN.
1245738753 -> 110e [CCall::makeGenericRequest] INVITE
1245738753 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = ;)
1245738753 -> 110e [CCall::getDialog] Returning Initial Dialog Client
1245738753 -> 1515 [CDialog::createRequest]
1245738753 -> 1515 [CDialog::buildServicesForAllRequest]
1245738753 -> 1515 [CDialog::createInviteRequest]
1245738753 -> 211c [CTransaction::changeState] STATE CHANGED TO INITIAL
1245738753 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738753 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Terminated, currentState = Initial, reason = None]
1245738753 -> 22 [CRequest::CRequest] Creation of a request
1245738753 -> 22 [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738753 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 835)
----------------------utf8-----------------------
INVITE sip:5338813@172.16.1.9;user=phone SIP/2.0
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
P-Asserted-Identity: "7407" <sip:7407@172.16.1.3>
Content-Type: application/sdp
To: <sip:5338813@172.16.1.9;user=phone>
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Contact: sip:172.16.1.3
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Max-Forwards: 70
Content-Length: 314

v=0
o=OXE 1245738753 1245738753 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.4
t=0 0
m=audio 32520 RTP/AVP 8 0 18 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=ptime:20
a=maxptime:40
a=rtpmap:97 telephone-event/8000
-------------------------------------------------
1245738753 -> 211c [CTransInitialState::createRequest] Transaction changes to Calling state
1245738753 -> 211c [CTransaction::changeState] STATE CHANGED TO CALLING
1245738753 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738753 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Initial, currentState = Calling, reason = Request creation]
1245738753 -> 211c [CTransaction::startTimer] Timer A is started (delay = 500 ms)
1245738753 -> 211c [CTransaction::startTimer] Timer B is started (delay = 32000 ms)
1245738753 -> [---*--- SESSION-TIMER ---*---] New CSessionTimerContext from request (Client, UA)

1245738753 -> [---*--- SESSION-TIMER ---*---] Update CSessionTimerContext (refresh creation)

1245738753 -> 110e[CMotorCall::makeInitialMotorRequest] nb contact=1.
1245738753 -> [CForkedBranch::CForkedBranch] context = 0x83aae04.
1245738753 -> 110e[CMotorCall::branchAllocation] built branch number=1.
1245738753 -> [onIncomingEvent] CallID must be inserted.
1245738753 -> [onIncomingEvent] CallID inserted.
1245738754 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------

1245738754 -> [CIOCom::receiveResponse] CResponse 2C ref=1
1245738754 -> [CDispatcher::onIncomingResponse]
1245738754 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738754 -> 102 [CCallManager::onIncomingResponse]
1245738754 -> 110e [CCall::receiveResponse] 100
1245738754 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738754 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738754 -> 110e [CCall::receiveResponse]One dialog found
1245738754 -> 1515 [CDialog::receiveResponse]
1245738754 -> 211c [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
1245738754 -> 211c [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1245738754 -> 211c [CTransaction::changeState] notifying the parent dialog
1245738754 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = Calling, currentState = Proceeding, reason = 1xx response reception]
1245738754 -> 211c [CTransaction::freeTimerToken] Timer A is freed
1245738754 -> 211c [CTransaction::freeTimerToken] Timer B is freed
1245738754 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738754 -> [receiveInformationalMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738756 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 141

v=0
o=root 386787 386787 IN IP4 172.16.1.9
s=Topex
c=IN IP4 172.16.1.9
t=0 0
m=audio 32518 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
-------------------------------------------------

1245738756 -> [CIOCom::receiveResponse] CResponse 2D ref=1
1245738756 -> [CDispatcher::onIncomingResponse]
1245738756 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738756 -> 102 [CCallManager::onIncomingResponse]
1245738756 -> 110e [CCall::receiveResponse] 183
1245738756 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738756 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738756 -> 110e [CCall::receiveResponse]One dialog found
1245738756 -> 1515 [CDialog::receiveResponse]
1245738756 -> 2C [~CResponse] resp(100) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738756 -> 1515 [CDialog::updateRouteSet] there are 0 record-route headers
1245738756 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738756 -> [receiveInformationalMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738756 -> [checkSdpValidity] Sdp contains 1 media description(s).
1245738756 -> [checkSdpValidity] Media 0 type 1 contains 1 formats.
1245738756 -> [checkSdpValidity] Format : 8.
1245738756 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1245738756 -> [convertSdpIntoTsdp] The audio media contains 1 format(s).
1245738756 -> [convertSdpIntoTsdp] Format 0 is 8.
1245738756 -> [convertSdpIntoTsdp] Get the direction.
1245738756 -> [convertSdpIntoTsdp] Direction is sendrecv.
1245738756 -> [convertSdpIntoTsdp] The connection is at session level.
1245738756 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.16.1.9.
1245738756 -> [convertIPStrIntoTuipv] 172.16.1.9 => 172.16.1.9
1245738756 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738756 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738756 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738756 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738756 -> [display_ipc_out] ------------ Begin ---------------
1245738756 -> INFORMATIONAL
1245738756 -> xx : 83
1245738756 -> RELATIVE REQUEST : INVITE
1245738756 -> SDP :
1245738756 -> ADDRESS : 172.16.1.9 :32518
1245738756 -> ALGOS :
1245738756 -> PCMA
1245738756 -> DIRECTION : SEND & RECEIVE
1245738756 -> [display_ipc_out] ------------- End ----------------
1245738760 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 INVITE
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
Contact: <sip:5338813@172.16.1.9:5060>
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 141

v=0
o=root 529264 529264 IN IP4 172.16.1.9
s=Topex
c=IN IP4 172.16.1.9
t=0 0
m=audio 32518 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
-------------------------------------------------

1245738760 -> [CIOCom::receiveResponse] CResponse 2E ref=1
1245738760 -> [CDispatcher::onIncomingResponse]
1245738760 -> [CDispatcher::processResponseFromManagerIndex] start at index 0
1245738760 -> 102 [CCallManager::onIncomingResponse]
1245738760 -> 110e [CCall::receiveResponse] 200
1245738760 -> 110e [CCall::receiveResponse]Trying to find the right dialog
1245738760 -> 110e [CCall::getDialog] Confirmed Dialog is not found (ID = 86dc8343e89f40424027da4702cad252;top7eaca361)
1245738760 -> 110e [CCall::receiveResponse]One dialog found
1245738760 -> 1515 [CDialog::receiveResponse]
1245738760 -> 1515 [CDialog::receiveResponse] create a CONFIRMED dialog
1245738760 -> 1516 [CDialog::CDialog] look for the transaction #0, transaction key = z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
1245738760 -> 1516 [CDialog::CDialog] copy the transaction #0, transaction key = z9hG4bK1ac9cf303e2c6aa3ecc983273712f8ee
1245738760 -> 211d [CTransaction::CTransaction] Transaction is cloned in 4 state
1245738760 -> 1516 [CDialog::startDialogTTL]
1245738760 -> 1516 [CDialog::receiveResponse]
1245738760 -> 211d [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
1245738760 -> 211d [CTransaction::changeState] STATE CHANGED TO COMPLETED
1245738760 -> 211d [CTransaction::changeState] notifying the parent dialog
1245738760 -> 1516 [CDialog::onTransactionState(pTrans = 211d, previousState = Proceeding, currentState = Completed, reason = Final resp reception]
1245738760 -> 211d [CTransaction::startTimer] Timer D is started (delay = 32361 ms)
1245738760 -> [---*--- SESSION-TIMER ---*---] New CSessionTimerContext from response (Client, UA)

1245738760 -> [---*--- SESSION-TIMER ---*---] Update CSessionTimerContext (response refresh reception)

1245738760 -> [---*--- SESSION-TIMER ---*---] No refresh

1245738760 -> 1516 [CDialog::updateRouteSet] there are 0 record-route headers
1245738760 -> 110e [CCall::insertInDialogMap] added dialog #1516 with the key 86dc8343e89f40424027da4702cad252;top7eaca361

1245738760 -> 1515 [CDialog::updateRouteSet] there are 0 record-route headers
1245738760 -> 110e[CMotorCall::searchForkedBranch]pForkedBranch=0x839e688.
1245738760 -> [CForkedBranch::setFinalResponse] pResponse=0x839c230.
1245738760 -> [CForkedBranch::setFinalResponse] response=200.
1245738760 -> 110e[CMotorCall::responseToInviteOrCancel] call chooseBestResponse.
1245738760 -> 110e[CMotorCall::chooseBestResponse] pForkedBranch=0x839e688 iResponseCode=2000.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> 110e[CMotorCall::chooseBestResponse] last iResponseCode=200.
1245738760 -> [CForkedBranch::setFinalResponse] pResponse=0x839c230.
1245738760 -> 110e[CMotorCall::chooseBestResponse] next branch.
1245738760 -> 110e[CMotorCall::responseToInviteOrCancel] inviteResponse => update the lastInviteContext.
1245738760 -> [receiveSuccessfulMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 PROCEEDING_STATE received a message.
1245738760 -> [checkSdpValidity] Sdp contains 1 media description(s).
1245738760 -> [checkSdpValidity] Media 0 type 1 contains 1 formats.
1245738760 -> [checkSdpValidity] Format : 8.
1245738760 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1245738760 -> [convertSdpIntoTsdp] default T38 config.
1245738760 -> [convertSdpIntoTsdp] The audio media contains 1 format(s).
1245738760 -> [convertSdpIntoTsdp] Format 0 is 8.
1245738760 -> [convertSdpIntoTsdp] Get the direction.
1245738760 -> [convertSdpIntoTsdp] Direction is sendrecv.
1245738760 -> [convertSdpIntoTsdp] The connection is at session level.
1245738760 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 172.16.1.9.
1245738760 -> [convertIPStrIntoTuipv] 172.16.1.9 => 172.16.1.9
1245738760 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738760 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738760 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738760 -> [ipc_thread] IPC Thread : Ipc reception.
1245738760 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738760 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738760 -> [display_ipc_out] ------------ Begin ---------------
1245738760 -> SUCCESSFUL
1245738760 -> xx : 0
1245738760 -> RELATIVE REQUEST : INVITE
1245738760 -> CLIR : 0
1245738760 -> COLP : 0
1245738760 -> SDP :
1245738760 -> ADDRESS : 172.16.1.9 :32518
1245738760 -> ALGOS :
1245738760 -> PCMA
1245738760 -> DIRECTION : SEND & RECEIVE
1245738760 -> [display_ipc_out] ------------- End ----------------
1245738760 -> 110e[CMotorCall::onReceiveResponse] INVITE case => cancelAllCancelableForkedBranch.
1245738760 -> 110e[CMotorCall::cancelAllCancelableForkedBranch] nb branch=1.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [CForkedBranch::getResponseCode] response=200.
1245738760 -> [exec_ipc] in.
1245738760 -> [display_ipc_in] ------------ Begin ---------------
1245738760 -> neqt : 716
1245738760 -> ACK
1245738760 -> [display_ipc_in] ------------- End ----------------
1245738760 -> [CMotorCallManager::onIncomingEvent] an event 10754 arrived on the eqt 716.
1245738760 -> [CMotorCallManager::onIncomingEvent] state = ACCEPTED_STATE.
1245738760 -> [onIncomingEvent] ACK arrived.
1245738760 -> [receiveAckEvent] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 ACCEPTED_STATE received an event.
1245738760 -> 110e[CMotorCall::emitAckMessage] begin.
1245738760 -> 110e[CMotorCall::emitAckMessage] SDP ok.
1245738760 -> 110e [CCall::makeGenericRequest] ACK
1245738760 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738760 -> 1516 [CDialog::createRequest]
1245738760 -> 1516 [CDialog::buildServicesForAllRequest]
1245738760 -> 1516 [CDialog::createAckRequest]
1245738760 -> 1516 [CDialog::createAckRequest], previous trans 211d is found
1245738760 -> 1516 [CDialog::createAckRequest], previous trans 211d is client
1245738760 -> 23 [CRequest::CRequest] Creation of a request
1245738760 -> 1516 [CDialog::buildServicesForAck]
1245738760 -> 23 [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738760 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 413)
----------------------utf8-----------------------
ACK sip:5338813@172.16.1.9:5060 SIP/2.0
Contact: sip:172.16.1.3
User-Agent: ABS GW v5.1.0
To: <sip:5338813@172.16.1.9;user=phone>;tag=top7eaca361
From: "7407" <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 635538580 ACK
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK7e9a8303002a2dd7b73012f48dc664fc
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------
1245738760 -> 211d [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738760 -> 211d [CTransaction::freeTimerToken] Timer D is freed
1245738760 -> 211d [CTransaction::changeState] no transaction state
1245738760 -> 1516 [CDialog::createAckRequest] successful INVITE is terminated after the ACK is sent
1245738760 -> [CTransaction::notifyDialog]
1245738760 -> [CTransaction::notifyDialog] notify parent dialog
1245738760 -> 1516 [CDialog::onTransactionState(pTrans = 211d, previousState = null, currentState = Terminated, reason = Request creation]
1245738769 -> RECEIVE MESSAGE FROM NETWORK (172.16.1.9:50195 [UDP])
----------------------utf8-----------------------
BYE sip:172.16.1.3 SIP/2.0
Via: SIP/2.0/UDP 172.16.1.9:5060;branch=z9hG4bK72d9de66
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 1 BYE
From: <sip:5338813@172.16.1.9:5060>;tag=top7eaca361
To: <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
Reason: Q.850 ;cause=16 ;text="Normal call clearing"
Supported: timer,replaces
Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,INFO,UPDATE,REFER,SUBSCRIBE,NOTIFY,PUBLISH
Max-Forwards: 70
Content-Length: 0

-------------------------------------------------

1245738769 -> 24 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1245738769 -> [CIOCom::receiveRequest] Received CRequest 24 ref=1
1245738769 -> [CDispatcher::onIncomingRequest()]
1245738769 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1245738769 -> 102 [CCallManager::onIncomingRequest()]
1245738769 -> 110e [CCall::receiveRequest] BYE
1245738769 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738769 -> 1516 [CDialog::receiveRequest]
1245738769 -> 1516 [CDialog::receiveBasicRequest]
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO INITIAL
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Terminated, currentState = Initial, reason = None]
1245738769 -> 211e [CTransInitialState::receiveRequest] Transaction changes to Trying state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO TRYING
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Initial, currentState = Trying, reason = Request reception]
1245738769 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1245738769 -> 110e [CCall::checkAuthentication] BYE
1245738769 -> 110e[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1245738769 -> 110e[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1245738769 -> 110e[CMotorCall::toGatewayOrProxy] request for proxydomain=172.16.1.3.
1245738769 -> [isUserRegistred] result=-1.
1245738769 -> 110e[CMotorCall::onReceiveRequest] request for gateway.
1245738769 -> 110e[CMotorCall::onReceiveRequest] state = COMPLETED_STATE.
1245738769 -> [receiveByeMessage] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 COMPLETED_STATE received a message.
1245738769 -> 110e[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1245738769 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1245738769 -> 110e[CMotorCall::emitEventToMonitel] CAC = 0
1245738769 -> [ipc_thread] IPC Thread : Ipc reception.
1245738769 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738769 -> 110e[CMotorCall::emitEventToMonitel] Event sent on eqt : 716
1245738769 -> [display_ipc_out] ------------ Begin ---------------
1245738769 -> BYE
1245738769 -> [display_ipc_out] ------------- End ----------------
1245738769 -> [exec_ipc] in.
1245738769 -> [display_ipc_in] ------------ Begin ---------------
1245738769 -> neqt : 716
1245738769 -> SUCCESSFUL
1245738769 -> xx : 0
1245738769 -> RELATIVE REQUEST : BYE
1245738769 -> CLIR : 0
1245738769 -> COLP : 0
1245738769 -> [display_ipc_in] ------------- End ----------------
1245738769 -> [CMotorCallManager::onIncomingEvent] an event 10765 arrived on the eqt 716.
1245738769 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1245738769 -> [onIncomingEvent] SUCCESSFUL arrived.
1245738769 -> [receiveSuccessfulEvent] Call: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 eqt: 716 TERMINATED_STATE received an event.
1245738769 -> 110e [CCall::findFirstServerTrans]
1245738769 -> 1516 [CCall::findFirstServerTrans]
1245738769 -> 1516 [CCallLeg::findFirstOpenServerTrans] Current Request in transactionMapByBranch
1245738769 -> 110e [CCall::makeGenericResponse] 200
1245738769 -> 110e [CCall::getDialog] Confirmed Dialog found
1245738769 -> 1516 [CDialog::createResponse] 200
1245738769 -> 211e [CTransTryingState::createResponse] CResponse 2F ref=1
1245738769 -> 2F [CMessage::send] sip_sendMessage (172.16.1.9:5060)
1245738769 -> SEND MESSAGE TO NETWORK (172.16.1.9:5060 [UDP]) (BUFF LEN = 401)
----------------------utf8-----------------------
SIP/2.0 200 OK
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, SUBSCRIBE, OPTIONS
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
To: <sip:7407@172.16.1.3>;tag=86dc8343e89f40424027da4702cad252
From: <sip:5338813@172.16.1.9:5060>;tag=top7eaca361
Call-ID: c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3
CSeq: 1 BYE
Via: SIP/2.0/UDP 172.16.1.9:5060;branch=z9hG4bK72d9de66
Content-Length: 0

-------------------------------------------------
1245738769 -> 211e [CTransTryingState::createResponse] Final : Transaction changes to Completed state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO COMPLETED
1245738769 -> 211e [CTransaction::changeState] notifying the parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = Trying, currentState = Completed, reason = Final resp creation]
1245738769 -> 211e [CTransaction::startTimer] Timer J is started (delay = 32000 ms)
1245738769 -> 1516 [CDialog::updateRouteSet] there are 0 record-route headers
1245738769 -> [ipc_thread] IPC Thread : Ipc reception.
1245738769 -> [ipc_thread] IPC Thread : TCL thread signaled.
1245738769 -> [exec_ipc] in.
1245738769 -> [display_ipc_in] ------------ Begin ---------------
1245738769 -> neqt : 716
1245738769 -> SIP EQT RELEASED
1245738769 -> [display_ipc_in] ------------- End ----------------
1245738769 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 716.
1245738769 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 716 has released its equipment.
1245738769 -> 110e[CMotorCall::unRegister] Remove eqt : 716 from the map.
1245738769 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 716 erased.
1245738769 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1245738769 -> [CMotorCallManager::onIncomingEvent] killSession.
1245738769 -> 110e [CCall::killSession]
1245738769 -> 211c [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738769 -> 211c [CTransaction::changeState] no transaction state
1245738769 -> 211e [CTransaction::changeState] STATE CHANGED TO TERMINATED
1245738769 -> 211e [CTransaction::freeTimerToken] Timer J is freed
1245738769 -> 211e [CTransaction::changeState] no transaction state
1245738769 -> 110e [CCall::onKill Session Call manager close is called]
1245738769 -> 110e [CCall::killSession] notifying the call manager
1245738769 -> 102 [CCallManager::onCallClosed]
1245738769 -> 102 [CCallManager::onCallClosed()] removing from the maps
1245738769 -> [CMotorCallManager::onNotifyCallClosed] Call : c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 is closed.
1245738769 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1245738769 -> [CCall::onKill] set callmanager to null
1245738769 -> [CTransaction::notifyDialog]
1245738769 -> [CTransaction::notifyDialog] notify parent dialog
1245738769 -> 1515 [CDialog::onTransactionState(pTrans = 211c, previousState = null, currentState = Terminated, reason = None]
1245738769 -> 1515 [CDialog::onTransactionState] dialog is terminated
1245738769 -> 1515 [CDialog::onTransactionState] onDialogClosed
1245738769 -> 110e [CCall::onDialogClosed]
1245738769 -> 110e [CCall::onDialogClosed] not only initial *********
1245738769 -> 110e [CCall::onDialogClosed]: the dialog 1516 is NOT closed
1245738769 -> 2D [~CResponse] resp(183) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> [CTransaction::notifyDialog]
1245738769 -> [CTransaction::notifyDialog] notify parent dialog
1245738769 -> 1516 [CDialog::onTransactionState(pTrans = 211e, previousState = null, currentState = Terminated, reason = None]
1245738769 -> 110e [CCall::onDialogClosed]
1245738769 -> 110e [CCall::onDialogClosed] not only initial *********
1245738769 -> 110e [CCall::onDialogClosed]: the dialog 1516 is closed
1245738769 -> 110e [CCall::onDialogClosed] All the dialogs in the Call are closed
1245738769 -> 102 [CCallManager::removeGarbage] at index i = 0
1245738769 -> 110e[CMotorCall::~CMotorCall] Call : c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 is being destructed.
1245738769 -> 110e [CCall::~CCall]
1245738769 -> 110e [CCall::removeGarbage]
1245738769 -> 1516 [CDialog::~CDialog]
1245738769 -> 1515 [CDialog::~CDialog]
1245738769 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1245738769 -> 23 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 ACK
1245738769 -> 2E [~CResponse] resp(200) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> 22 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 635538580 INVITE
1245738769 -> 2F [~CResponse] resp(200) c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 1 BYE
1245738769 -> 24 [~CRequest] req c9bdf9e7fc74b9828979a41fd2db9b86@172.16.1.3 1 BYE


--------------------------------------
трасса этого звонка с gayeway
SETUP
call_type: normalcallin
ani: 7407;pr_alwd;scrn_netw
presentation: 7407;pr_alwd;scrn_netw
dnis: 5338813
src_sign_addr: 172.16.1.3:10005
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000
audio_codec: pt=0;ms=20;vad=0;rxtx=2;smap=PCMU/8000
audio_codec: pt=18;ms=20;vad=0;rxtx=2;smap=G729/8000
audio_codec: pt=97;ms=20;vad=0;rxtx=2;smap=telephone-event/8000
audio_addr: 172.16.1.4:32520
a_sip: user=255
nat: no
b_sip: user=255

08:30:52,563863 16383 7 voipidle00
08:30:52,564827 16383 7 cctl_rxSETUP codec 8 rtpmap
08:30:52,565450 16383 7 cctl_rxSETUP codec 0 rtpmap
08:30:52,566074 16383 7 cctl_rxSETUP codec 18 rtpmap
08:30:52,566695 16383 7 cctl_rxSETUP codec 97 rtpmap
08:30:52,567320 16383 7 cctl_rxSETUP fast_start 1
08:30:52,568035 16383 7 voipidle00 Rx SETUP ani '7407' dnis '5338813' -> Tx SETUP_ACK
08:30:52,568792 16383 7 SendMessISDN sursa 7 dest 65535 tip 254 len 24 proto 4
08:30:52,569409 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,570079 16383 7 SendMess2SIP src 7 dst 65535 tip 254 len 24 proto 4
08:30:52,571901 16383 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:52,572546 16383 7 SendMesPBX2SIP Tx src 7 dst 263 len 24 pid_ua 1...

SETUP_ACK
sid: 5e6ba664

08:30:52,573869 16383 7 SendMesPBX2SIP Tx to dest 263
08:30:52,574522 16383 7 LiveMonitorSetup
08:30:52,575210 16383 7 in_check_access_1
08:30:52,575846 16383 7 in_check_access_1 NR_DIRIN_PBX 4000
08:30:52,576521 16383 7 in_check_access_1 0 ip1 ac100103 mask ffffffff ip2 ac100103
08:30:52,577159 16383 7 in_check_access_1 digits 5338813 prg_diripin[0].digits
08:30:52,577843 16383 7 in_check_access_1 Found diripin from_pbx, 0 simult calls from 172.16.1.3/32 proxy 0 transcoding=0 ssw_calls 0 max_ssw_calls 100000
08:30:52,578497 16383 7 CheckCallIn class 'from_pbx' nrcallin 1 maxspeachin 0 speachin 0 maxcostin 0.000000 costin 0.000000, ssw_calls 1
08:30:52,579234 16383 7 CountSysCallIn IN SYSTEM medium_cps 0.000000 max_cps 0 MAX 0
08:30:52,579932 16383 7 CountSysCallIn IN SYSTEM sec 1245735052 calls in this sec 1
08:30:52,581322 16383 7 CountCallInOn IN dir nr 21 name from_pbx medium_cps 0.000000 max_cps 0 MAX 0 calls IN 1
08:30:52,582021 16383 7 CountCallInOn IN dir nr 21 name from_pbx sec 1245735052 calls in this sec 1
08:30:52,582707 16383 7 voipidle00 have all digits fast_start is 1
08:30:52,583344 16383 7 SendMessISDN sursa 7 dest 65535 tip 130 len 1 proto 4
08:30:52,583972 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,584594 16383 7 SendMess2SIP src 7 dst 65535 tip 130 len 1 proto 4
08:30:52,585286 16383 7 SendMessISDN sursa 7 dest 65535 tip 254 len 36 proto 4
08:30:52,585913 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,586702 16383 7 SendMess2SIP src 7 dst 65535 tip 254 len 36 proto 4
08:30:52,587345 16383 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:52,587994 16383 7 SendMesPBX2SIP Tx src 7 dst 263 len 36 pid_ua 1...

ACM
bci: isup_used_alltheway;charge

08:30:52,589340 16383 7 SendMesPBX2SIP Tx to dest 263
08:30:52,589999 16383 7 voipidle00 Instal(dialdigits, 30000)
08:30:52,591469 16383 7 Set_Timer tmr 0 val 30000
08:30:52,684294 16383 7 dialdigits ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x0000
08:30:52,684965 16383 7 dialdigits M_RUN
08:30:52,685718 16383 7 dialdig_run ta->rtpproxy 0
08:30:52,686380 16383 7 dialdig_run pupi_db_id len 0, number 5338813
08:30:52,687130 16383 7 GetDest GetDest rn 0
08:30:52,687780 16383 7 GetDest classid 21
08:30:52,688412 16383 7 GetDest search_bin_tradu class 21 alldigit 1
08:30:52,689182 16383 7 search_nr_jonbin digit 5 first_poz 4 nr_poz 1
08:30:52,689859 16383 7 GetDest poz 7fff len 0 match 0 noa 3
08:30:52,691312 16383 7 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
08:30:52,692058 16383 7 GetDest tryf poz 32767 len 0 match 0 noa 3
08:30:52,692721 16383 7 GetDest try with default route
08:30:52,693420 16383 7 GetDest GetDest rn 0
08:30:52,694230 16383 7 GetDest classid 65535
08:30:52,694904 16383 7 GetDest search_bin_tradu class 65535 alldigit 1
08:30:52,695671 16383 7 search_nr_jonbin digit 5 first_poz 4 nr_poz 1
08:30:52,696329 16383 7 GetDest poz 9004 len 7 match 1 noa 3
08:30:52,697081 16383 7 GetDest route 0x9004 rlen 7 rmatch 1 r 0x4049575c nr 0
08:30:52,697757 16383 7 GetDest find 1 routes
08:30:52,698378 16383 7 GetDest find maxpoz 4
08:30:52,699126 16383 7 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
08:30:52,699803 16383 7 GetDest find all f tryf poz 32767 len 7 match 1 noa 3
08:30:52,701265 16383 7 GetDest find ok routes 1
08:30:52,701901 16383 7 GetDest DIRACTION poz 0x9004 idx 4 dest 0x1 type_dir 0x1
08:30:52,702700 16383 7 play_announcement Missing route play_file -> return
08:30:52,703415 16383 7 dialdig_run ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x9004
08:30:52,704177 16383 7 dialdig_run tax from tradu is 0 bpf_r_in 0 connection time is 0 min
08:30:52,704923 16383 7 get_maxproxy_in proxyin 0
08:30:52,705661 16383 7 dialdig_recmes_switch DIRACTION
08:30:52,706300 16383 7 dialdig_recmes_switch nrtrytodir 0
08:30:52,707140 16383 7 try_play_time ppu is NULL -> return 0
08:30:52,707809 16383 7 dialdig_recmes_switch NewCallJon(nr_out '5338813', id_out '7407')
08:30:52,708572 16383 7 NewCallJon route 36868
08:30:52,709352 16383 7 NewCallJon GetRoute 1
08:30:52,710034 16383 7 GetRoute route 4 mode 0 data 0
08:30:52,712203 16383 7 GetRoute route 4 mode 0 data 0 dir GSM ASR 0.000000
08:30:52,712950 16383 7 GetRoute return route 9004 ASR max 0.000000 on dir 1
08:30:52,713801 16383 7 NewCallJon global_route 0 fork_alg 0
08:30:52,714450 16383 7 NewCallJon route 9004 poz 4
08:30:52,715085 16383 7 NewCallJon dir GSM proto UNKNOWN nr_out '5338813' id_out '7407'
08:30:52,715854 16383 7 NewCallJon dir 1 translate_out 0
08:30:52,716569 16383 7 try_alloc_transcoding
08:30:52,717375 16383 7 try_alloc_transcoding 3
08:30:52,718235 16383 7 CallJonDir IP to TDM
08:30:52,719053 16383 7 CallJonDir find portB 8 ch 0 0
08:30:52,719775 00259 7 cctl_allocRTPportBIGSW find transcoding port 259
08:30:52,721244 00259 7 Port2Slot port 259 type_eq 2

08:30:52,721871 00259 7 Port2Slot WARN port 259 pid 65535
08:30:52,722489 00259 7 Port2Slot WARN return slot -108:30:52,723110 00259 7 SendSETTIMESLOT WARN VOIP port 259
08:30:52,723736 00259 7 get_port card 32 sign_card 32512 port 259
08:30:52,724368 00259 7 SetParamRTPLOCAL aPORT 32518
08:30:52,724989 00259 7 get_maxproxy_in proxyin 0
08:30:52,725640 00259 7 cctl_allocRTPportBIGSW fast_start is 1 aPT[0].ptime is 20
08:30:52,726319 00259 7 match_codec_poz match codec 8 ptime 20 vad 0
08:30:52,727010 00259 7 SetStare port 259 state old 0 new 1
08:30:52,727698 00259 7 LiveMonitorSetup
08:30:52,728317 00259 7 TxPublish port 259 state 1,rx_dialog_sub 0 sip_pid 65535
08:30:52,728957 00259 7 CallJonDir find portA 259
08:30:52,729589 00259 7 SendCall mod 1, work 0x00000023, dirname 'GSM', proto 'UNKNOWN' nrcalls 0, prtb 8 nextroute 36868
08:30:52,730932 00259 7 SendCall ALLDIGITBIT -> SendCallJon
08:30:52,731622 00259 7 SendCallJon sign 0x0 ta->RB_pi 0x0
08:30:52,732250 00259 7 SendCallJon work 51
08:30:52,732868 00259 7 SendCallJon SendMessCallTDM len 30
08:30:52,733556 00259 7 SendMessCallTDM len 30
08:30:52,734451 00259 7 SendMessCallTDM GetProcTa() 8
08:30:52,735074 00259 7 SendMessCallTDM cat1 0 type 0 proto 'CAS'
08:30:52,735758 00259 7 CopyToB_pid dnis_noa 3
08:30:52,736390 00259 7 CopyToB_pid category 10
08:30:52,737011 00259 7 CopyToB_pid ani_noa 3 pi 0 si 0
08:30:52,737635 00259 7 CopyToB_pid category 10
08:30:52,738253 00259 7 CopyToB_pid tmr 3
08:30:52,738874 00259 7 CopyToB_pid action 1 proto 1 dir 1 routedir 1 transport 0 media
08:30:52,739502 00259 7 CopyToB_pid call_info 100
08:30:52,740120 00259 7 CopyToB_pid orig_dnis '' redirect_by ''
08:30:52,741631 00259 7 SendMessCallTDM increment nrcallout[GSM] new_val 1
08:30:52,742397 00259 7 CountSysCallOut OUT SYSTEM medium_cps 0.000000 max_cps 0 MAX 0
08:30:52,743119 00259 7 CountSysCallOut OUT SYSTEM sec 1245735052 calls in this sec 1
08:30:52,743788 00259 7 CountCallOut OUT dir nr 1 name GSM medium_cps 0.000000 max_cps 0 MAX 0 calls OUT 1
08:30:52,744487 00259 7 CountCallOut OUT dir nr 1 name GSM sec 1245735052 calls in this sec 1
08:30:52,745121 00259 7 SendMessCallTDM is_user_ccs 0 -> Tx M_CALL len 30 to 8 ani_pi 0
08:30:52,745759 00259 7 FuncTabPort GSM -> SetFunc(pid 8, gsm00)
08:30:52,746386 00259 7 SendMessCallTDM global_b_pid 8
08:30:52,747058 00259 7 Set_Timer tmr 1 val 200
08:30:52,747701 00259 7 Set_Timer tmr 0 val 202
08:30:52,748334 00259 7 SendCall end
08:30:52,748951 00259 7 NewCallJon global_b_pid 8
08:30:52,749629 00259 7 NewCallJon ret 0 nrtrytodir 1 param 0
08:30:52,750962 00259 7 EndNewCallJon ret 0
08:30:52,751576 00259 7 InformPortB port 259
08:30:52,752192 00259 7 InformPortB port 259
08:30:52,752815 00259 7 EndNewCallJon ret 0 find PORTB 8
08:30:52,754227 00008 8 gsm00 stare_port 0 sim_port 0
08:30:52,754881 00008 8 gsm00 M_CALL mod 1
08:30:52,755576 00008 8 GetParamM_CALL ANI '7407' DNIS '5338813'
08:30:52,756205 00008 8 gsm00 M_CALL mod 1 cat 1 b_pid 7 prtb 103 s170
08:30:52,757005 00008 8 gsm00 Digits 5338813 Ident 7407
08:30:52,757653 00008 8 GetCh flow 1 channel 2
08:30:52,758299 00008 8 SetStare port 8 state old 0 new 1
08:30:52,759017 00008 8 SendM Tx SERIAL CAS/ISDN card 1 len 3 data 30 02 00
08:30:52,759729 00008 8 LiveMonitorSetup

08:30:52,761670 00008 8 MTxPublish b_pid 7 prtb 259
08:30:52,762291 00008 8 MTxPublish b_pid prt 259
08:30:52,762907 00008 8 MTxPublish b_pid prtpid 263
08:30:52,763526 00008 8 TxPublish port 8 state 1,rx_dialog_sub 0 sip_pid 263
08:30:52,764163 00008 8 Set_Timer tmr 0 val 1
08:30:52,764891 00008 8 MTxPublish b_pid 7 prtb 259
08:30:52,765520 00008 8 MTxPublish b_pid prt 259
08:30:52,766147 00008 8 MTxPublish b_pid prtpid 263
08:30:52,766768 00008 8 TxPublish port 8 state 2,rx_dialog_sub 0 sip_pid 263
08:30:52,767 00008 217610 Tx GSM ATD5338813i;
08:30:52,768130 00008 8 translateGSMMES buffering len 14 cardISDN 3 lenbuf 0
08:30:52,768820 00008 8 translateGSMMES Tx GSM len 14 33 38 38 31 00 33 69 3b
08:30:52,769430 00008 8 Set_Timer tmr 0 val 3000
08:30:52,770102 00259 7 dialdigits ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x9004
08:30:52,771527 00259 7 dialdigits ACK to M_CALL work 33 b_pid 8
08:30:52,772223 00259 7 dialdigits Instal(dialdest, 30000)
08:30:52,772859 00259 7 Set_Timer tmr 0 val 30000
08:30:52,773531 65535 8 RunWhileMain int in int retval 422864
08:30:52,774456 65535 212 SendM Tx SERIAL CAS/ISDN card 1 len 14 data 08 0c 00 44 35 33 33 38 38 31 00 33 69 3b
08:30:52,811758 65535 349 FuncCardUnBlock2ME1ISDN

8:30:55,358478 00259 7 GetLocalRtpIpPort
08:30:55,359097 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:30:55,359763 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:30:55,360913 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:55,361660 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:30:55,362317 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518
08:30:55,363098 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 109 proto 4
08:30:55,363727 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:55,364358 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 109 proto 4
08:30:55,364998 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:55,365634 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 109 pid_ua 1...

PROGRESS
event: inband_info
audio_addr: 172.16.1.9:32518
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000

08:30:55,366906 00259 7 SendMesPBX2SIP Tx to dest 263
08:30:55,367641 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:55,368291 00259 7 Port2Slot port 259 type_eq 2
08:30:55,368920 00259 7 Port2Slot port 8 ch 2 slot 162
08:30:55,369538 00259 7 Port2Slot slot 162 slotport[674]=259 return slot 162
08:30:55,370177 00259 7 VoipTxOnOffSlotVoip slot 162
08:30:55,371908 00259 7 msp_mgr_msg_tx rslot 162
08:30:55,372872 00259 7 XCONNECTVOIP vad 255
08:30:55,373541 00259 7 XCONNECTVOIP txrx 2
08:30:55,374291 00259 7 XCONNECTVOIP rtpproxy 0, DST_proxy 65535
08:30:55,374956 00259 7 XCONNECTVOIP VOIP_RTCP 0 VOIP_any 0 rtpudp 0
08:30:55,375787 00259 7 msp_mgr_msg_tx Rrtp 162 -sid "5e6ba664" -p 8 -m 20 32518 172.16.1.4 32520
08:30:55,376618 00259 7 dialdest M_CONNECT -> XCONNECTVOIPA()
08:30:55,377420 00259 7 dialdest Tx M_PINFO:RING to port 259
08:30:55,378083 00259 7 SendMessISDN sursa 7 dest 259 tip 130 len 2 proto 4
08:30:55,378825 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:55,379475 00259 7 SendMess2SIP src 7 dst 259 tip 130 len 2 proto 4
08:30:55,380149 00259 7 MTxPublish b_pid 8 prtb 8
08:30:55,381451 00259 7 TxPublish port 259 state 3,rx_dialog_sub 0 sip_pid 65535
08:30:55,382219 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:55,382891 00259 7 XCONNECTVOIP str_tratap->make_RTP 1 -> return
08:30:55,383519 00259 7 dialdest Instal(preanswerA, 180000)
08:30:55,384317 00259 7 Set_Timer tmr 0 val 180000
08:30:55,471622 00259 7 preanswerA WARN Unknown msg 78 -> ign
08:30:55,472318 65535 8 RunWhileMain int in int retval 121552
08:30:55,473417 65535 349 FuncCardUnBlock2ME1ISDN
08:30:55,474218 65535 349 add_ISDNcard card 16 type 1 dsp -1
08:30:55,474853 65535 349 add_ISDNcard card 16 type 1 chfl 50
08:30:55,475484 65535 349 GetJonDirDMaHDLC dir 9 eq 0
08:30:55,476126 65535 349 FindDSPISDNFromVoIP card 16 portvoip 65535
08:30:55,476754 65535 349 start_ISDN card 16 type 1 port 0
08:30:55,481320 65535 349 FuncCardUnBlock2ME1ISDN

08:30:58,495673 65535 344 analize_mes_ana Rx SERIAL ISDN adr 8 tip 78 len 4 data 61 78 03 00 4f 4b
08:30:58,496309 65535 344 analize_mes_ana buflng 7
08:30:58,496928 65535 344 translateINGSMMES len 3 nrpack 0 rest 3
08:30:58,497718 65535 344 analize_mes_ana buflng 6
08:30:58,498342 65535 344 CardComSetState card 1 up 1 old_stat 0
08:30:58,499097 65535 344 DispatchFromPort === 1 tip 125
08:30:58,499739 65535 344 DispatchFromPort M_PORTISDN port 8
08:30:58,500608 65535 344 DispatchFromPort MSKJON
08:30:58,501263 65535 344 DispatchFromPort P_KEYPAD dest 8 len 3 debug_run 1 debug_gsm 0 moniport 1
08:30:58,502 00008 223750 Rx GSM OK
08:30:58,502737 00008 8 gpreanswerB rx OK
08:30:58,503523 00008 8 XCONNECTVOIP TypeModulFromAddr != VOIP -> return
08:30:58,504367 00008 8 InitTimpTax rad_bill 0 tx_accounting_start 1
08:30:58,505013 00008 8 gpreanswerB OK -> ANSWER
08:30:58,505750 00008 8 MTxPublish b_pid 7 prtb 259
08:30:58,506393 00008 8 MTxPublish b_pid prt 259
08:30:58,507129 00008 8 MTxPublish b_pid prtpid 263
08:30:58,507782 00008 8 TxPublish port 8 state 4,rx_dialog_sub 0 sip_pid 263
08:30:58,508520 00259 7 preanswerA M_OFFHOOK
08:30:58,509289 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:58,509964 00259 7 XCONNECTVOIP str_tratap->make_RTP 1 -> return
08:30:58,511476 00259 7 SendTaxPortConnect r_tax 0
08:30:58,512235 00259 7 SendMessISDN sursa 7 dest 259 tip 131 len 1 proto 4
08:30:58,512885 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:58,513512 00259 7 SendMess2SIP src 7 dst 259 tip 131 len 1 proto 4
08:30:58,514313 00259 7 SendMess2SIP TxAsciMedia(), SendMessISDN(proto 4)
08:30:58,514974 00259 7 GetLocalRtpIpPort
08:30:58,515713 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:30:58,516400 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:30:58,517170 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:58,517898 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:30:58,518554 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518
08:30:58,519435 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 99 proto 4
08:30:58,520066 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:58,521044 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 99 proto 4
08:30:58,521685 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:58,522443 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 99 pid_ua 1...

ANSWER
proto: CAS
audio_addr: 172.16.1.9:32518
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000

08:30:58,523867 00259 7 SendMesPBX2SIP Tx to dest 263
08:30:58,524701 00259 7 InitTimpTax rad_bill 0 tx_accounting_start 1
08:30:58,525459 00259 7 MTxPublish b_pid 8 prtb 8
08:30:58,526129 00259 7 TxPublish port 259 state 4,rx_dialog_sub 0 sip_pid 65535
08:30:58,526771 00259 7 preanswerA GoTo(answerA)
08:30:58,533861 65535 8 S_GetTr malloc(32)
08:30:58,541133 65535 8 RunWhileMain int in int retval 50268
08:30:58,557420 65535 177 SendMesSIP2PBX Rx src 263 dst 7 len 11 ...

ANSWER_ACK

08:30:58,558969 00259 7 answerA PROXYASCII to b_pid 8
08:30:58,559872 00008 8 GetLocalRtpIpPort
08:30:58,561340 00008 8 get_pattern_addr bIP.IP 0 bIPs.IP 0 remoteID
08:30:58,561967 00008 8 get_pattern_addr WARN can't get B IP
08:30:58,562785 00008 8 GetLocalRtpIpPort b_pid 7 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 0.0.0.0 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:58,563464 00008 8 get_group_rtp_ip ERROR group 0 pattern 0.0.0.0, no class matched -> return 0.0.0.0
08:30:58,564240 00008 8 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 0.0.0.0:65535
08:30:58,564899 00008 8 cctl_M_PROXYASCII rez is 1(RTP proxy,transc on) ..I must send my data
08:30:58,565717 00008 8 SendMessISDN sursa 8 dest 8 tip 254 len 11 proto 1
08:30:58,566367 00008 8 SendMessISDN port 8 tip 254
08:30:58,567151 00008 8 SendMessISDN src 8 dst 8 tip 254 len 11 proto 1
08:30:58,567851 00008 8 SendMessISDN port type 0 and card type 11 != BRICARD|RGCARD -> return 387
08:30:58,581898 65535 349 FuncCardUnBlock2ME1ISDN

08:31:07,825359 65535 349 add_ISDNcard card 17 type 1 dsp -1
08:31:07,825989 65535 349 add_ISDNcard card 17 type 1 chfl 70
08:31:07,826620 65535 349 GetJonDirDMaHDLC dir 9 eq 0
08:31:07,827260 65535 349 FindDSPISDNFromVoIP card 17 portvoip 65535
08:31:07,827889 65535 349 start_ISDN card 17 type 1 port 0
08:31:07,860784 65535 347 analize_mes_ana Rx SERIAL ISDN adr 8 tip 78 len 13 data 61 78 0c 00 4e 4f 20 43 41 52 52 00 49 45 52
08:31:07,861425 65535 347 analize_mes_ana buflng 16
08:31:07,862047 65535 347 translateINGSMMES len 12 nrpack 1 rest 4
08:31:07,862832 65535 347 analize_mes_ana buflng 14
08:31:07,863450 65535 347 CardComSetState card 1 up 1 old_stat 0
08:31:07,864233 65535 347 DispatchFromPort === 1 tip 125
08:31:07,864880 65535 347 DispatchFromPort M_PORTISDN port 8
08:31:07,865621 65535 347 DispatchFromPort MSKJON
08:31:07,866267 65535 347 DispatchFromPort P_KEYPAD dest 8 len 11 debug_run 1 debug_gsm 0 moniport 1
08:31:07,867 00008 233110 Rx GSM NO CARRIER
08:31:07,867763 00008 8 answerB M_PKEYPAD -> handle_M_PKEYPAD()
08:31:07,868465 00008 8 handle_M_PKEYPAD Tx M_CANCELDN to b_pid 7, TxDisactPort()
08:31:07,869449 00008 8 get_sign_media aIP 0.0.0.0:65535
08:31:07,870149 00008 8 GetLocalRtpIpPort
08:31:07,871518 00008 8 get_pattern_addr bIP.IP 0 bIPs.IP 0 remoteID
08:31:07,872257 00008 8 get_pattern_addr WARN can't get B IP
08:31:07,872943 00008 8 GetLocalRtpIpPort b_pid 7 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 0.0.0.0 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:31:07,873621 00008 8 get_group_rtp_ip ERROR group 0 pattern 0.0.0.0, no class matched -> return 0.0.0.0
08:31:07,874431 00008 8 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 0.0.0.0:65535
08:31:07,875110 00008 8 get_sign_media sourceIP :0 sourceRTP 0.0.0.0:65535 destIP 172.16.1.9:0 destRTP 0.0.0.0:65535
08:31:07,875857 00008 8 MTxPublish b_pid 7 prtb 259
08:31:07,876502 00008 8 MTxPublish b_pid prt 259
08:31:07,877251 00008 8 MTxPublish b_pid prtpid 263
08:31:07,877901 00008 8 TxPublish port 8 state 5,rx_dialog_sub 0 sip_pid 263
08:31:07,878711 00008 8 TxDisactPort
08:31:07,879494 00008 8 UnalocDTMF WARN no voip slot alocated
08:31:07,880116 00008 8 TxDisactPort code 16
08:31:07,881272 00008 8 SendM Tx SERIAL CAS/ISDN card 1 len 2 data 38 7f
08:31:07,881967 00008 8 Port2Slot port 8 type_eq 2
08:31:07,882783 00008 8 Port2Slot port 8 ch 2 slot 162
08:31:07,883450 00008 8 Port2Slot return slot 162
08:31:07,884095 00008 8 VoipTxOnOffSlotVoip slot 162
08:31:07,884806 00008 8 msp_mgr_msg_tx rslot 162
08:31:07,885724 00008 8 UnalocCh flow 1 channel 2
08:31:07,886382 00008 8 Set_Timer tmr 0 val 1500
08:31:07,887386 00259 7 kill_rtp ta->prtd 65535 ta->e_pid 65535
08:31:07,888054 00259 7 kill_rtp Tx M_CANCELDN to prtd 65535
08:31:07,888683 00259 7 kill_rtp Tx M_RESETINFO to rtp pid 65535
08:31:07,889481 00259 7 SendMes WARN dest pid 65535 is higher than nrproc 354 -> msg not sent
08:31:07,890176 00259 7 answerA M_CANCELDN
08:31:07,892062 00259 7 update_prepaid_credit ppu (nil), OD_ppu (nil), RB_ppu (nil)
08:31:07,892986 00259 7 get_sign_media aIP 172.16.1.9:32518
08:31:07,893697 00259 7 GetLocalRtpIpPort
08:31:07,894468 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:31:07,895148 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:31:07,895920 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:31:07,896644 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:31:07,897420 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518

08:31:07,898117 00259 7 get_sign_media sourceIP 172.16.1.3:10005 sourceRTP 172.16.1.4:32520 destIP 172.16.1.9:0 destRTP 172.16.1.9:32518
08:31:07,898757 00259 7 MTxPublish b_pid 8 prtb 8
08:31:07,899556 00259 7 TxPublish port 259 state 5,rx_dialog_sub 0 sip_pid 65535
08:31:07,901205 00259 7 do_tax tip 'C' duration 9 finalize 'BOK' sid 5e6ba664
08:31:07,901844 00259 7 do_tax SIGN_s 172.16.1.3:10005 RTP_s 172.16.1.4:32520 SIGN_d 172.16.1.9:0 RTP_d 172.16.1.9:32518
08:31:07,903204 00259 7 TxDisactPort
08:31:07,903945 00259 7 UnalocDTMF WARN no voip slot alocated
08:31:07,904597 00259 7 TxDisactPort code 16
08:31:07,905224 00259 7 Port2Slot port 8 type_eq 2
08:31:07,905968 00259 7 Port2Slot WARN return slot -108:31:07,906614 00259 7 SendRESTIMESLOT WARN mombasa and VoIP port -> return
08:31:07,907363 00259 7 SendMessISDN sursa 7 dest 259 tip 134 len 1 proto 4
08:31:07,908021 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:31:07,908643 00259 7 SendMess2SIP src 7 dst 259 tip 134 len 1 proto 4
08:31:07,909514 00259 7 SendMess2SIP nex 7 mod 0 mcpsin 0.000000 curcpsin 1
08:31:07,910916 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 18 proto 4
08:31:07,911582 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:31:07,912326 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 18 proto 4
08:31:07,912985 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:31:07,913617 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 18 pid_ua 1...

RELEASE
cause: 16

08:31:07,915040 00259 7 SendMesPBX2SIP Tx to dest 263
08:31:07,915815 00259 7 Set_Timer tmr 0 val 6000
08:31:07,920742 65535 8 SendMesSIP2PBX Rx src 263 dst 7 len 17 ...

RELEASE_COMPLETE

--------------------------------------
трасса этого звонка с gayeway
SETUP
call_type: normalcallin
ani: 7407;pr_alwd;scrn_netw
presentation: 7407;pr_alwd;scrn_netw
dnis: 5338813
src_sign_addr: 172.16.1.3:10005
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000
audio_codec: pt=0;ms=20;vad=0;rxtx=2;smap=PCMU/8000
audio_codec: pt=18;ms=20;vad=0;rxtx=2;smap=G729/8000
audio_codec: pt=97;ms=20;vad=0;rxtx=2;smap=telephone-event/8000
audio_addr: 172.16.1.4:32520
a_sip: user=255
nat: no
b_sip: user=255

08:30:52,563863 16383 7 voipidle00
08:30:52,564827 16383 7 cctl_rxSETUP codec 8 rtpmap
08:30:52,565450 16383 7 cctl_rxSETUP codec 0 rtpmap
08:30:52,566074 16383 7 cctl_rxSETUP codec 18 rtpmap
08:30:52,566695 16383 7 cctl_rxSETUP codec 97 rtpmap
08:30:52,567320 16383 7 cctl_rxSETUP fast_start 1
08:30:52,568035 16383 7 voipidle00 Rx SETUP ani '7407' dnis '5338813' -> Tx SETUP_ACK
08:30:52,568792 16383 7 SendMessISDN sursa 7 dest 65535 tip 254 len 24 proto 4
08:30:52,569409 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,570079 16383 7 SendMess2SIP src 7 dst 65535 tip 254 len 24 proto 4
08:30:52,571901 16383 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:52,572546 16383 7 SendMesPBX2SIP Tx src 7 dst 263 len 24 pid_ua 1...

SETUP_ACK
sid: 5e6ba664

08:30:52,573869 16383 7 SendMesPBX2SIP Tx to dest 263
08:30:52,574522 16383 7 LiveMonitorSetup
08:30:52,575210 16383 7 in_check_access_1
08:30:52,575846 16383 7 in_check_access_1 NR_DIRIN_PBX 4000
08:30:52,576521 16383 7 in_check_access_1 0 ip1 ac100103 mask ffffffff ip2 ac100103
08:30:52,577159 16383 7 in_check_access_1 digits 5338813 prg_diripin[0].digits
08:30:52,577843 16383 7 in_check_access_1 Found diripin from_pbx, 0 simult calls from 172.16.1.3/32 proxy 0 transcoding=0 ssw_calls 0 max_ssw_calls 100000
08:30:52,578497 16383 7 CheckCallIn class 'from_pbx' nrcallin 1 maxspeachin 0 speachin 0 maxcostin 0.000000 costin 0.000000, ssw_calls 1
08:30:52,579234 16383 7 CountSysCallIn IN SYSTEM medium_cps 0.000000 max_cps 0 MAX 0
08:30:52,579932 16383 7 CountSysCallIn IN SYSTEM sec 1245735052 calls in this sec 1
08:30:52,581322 16383 7 CountCallInOn IN dir nr 21 name from_pbx medium_cps 0.000000 max_cps 0 MAX 0 calls IN 1
08:30:52,582021 16383 7 CountCallInOn IN dir nr 21 name from_pbx sec 1245735052 calls in this sec 1
08:30:52,582707 16383 7 voipidle00 have all digits fast_start is 1
08:30:52,583344 16383 7 SendMessISDN sursa 7 dest 65535 tip 130 len 1 proto 4
08:30:52,583972 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,584594 16383 7 SendMess2SIP src 7 dst 65535 tip 130 len 1 proto 4
08:30:52,585286 16383 7 SendMessISDN sursa 7 dest 65535 tip 254 len 36 proto 4
08:30:52,585913 16383 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:52,586702 16383 7 SendMess2SIP src 7 dst 65535 tip 254 len 36 proto 4
08:30:52,587345 16383 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:52,587994 16383 7 SendMesPBX2SIP Tx src 7 dst 263 len 36 pid_ua 1...

ACM
bci: isup_used_alltheway;charge

08:30:52,589340 16383 7 SendMesPBX2SIP Tx to dest 263
08:30:52,589999 16383 7 voipidle00 Instal(dialdigits, 30000)
08:30:52,591469 16383 7 Set_Timer tmr 0 val 30000
08:30:52,684294 16383 7 dialdigits ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x0000
08:30:52,684965 16383 7 dialdigits M_RUN
08:30:52,685718 16383 7 dialdig_run ta->rtpproxy 0
08:30:52,686380 16383 7 dialdig_run pupi_db_id len 0, number 5338813
08:30:52,687130 16383 7 GetDest GetDest rn 0
08:30:52,687780 16383 7 GetDest classid 21
08:30:52,688412 16383 7 GetDest search_bin_tradu class 21 alldigit 1
08:30:52,689182 16383 7 search_nr_jonbin digit 5 first_poz 4 nr_poz 1
08:30:52,689859 16383 7 GetDest poz 7fff len 0 match 0 noa 3
08:30:52,691312 16383 7 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
08:30:52,692058 16383 7 GetDest tryf poz 32767 len 0 match 0 noa 3
08:30:52,692721 16383 7 GetDest try with default route
08:30:52,693420 16383 7 GetDest GetDest rn 0
08:30:52,694230 16383 7 GetDest classid 65535
08:30:52,694904 16383 7 GetDest search_bin_tradu class 65535 alldigit 1
08:30:52,695671 16383 7 search_nr_jonbin digit 5 first_poz 4 nr_poz 1
08:30:52,696329 16383 7 GetDest poz 9004 len 7 match 1 noa 3
08:30:52,697081 16383 7 GetDest route 0x9004 rlen 7 rmatch 1 r 0x4049575c nr 0
08:30:52,697757 16383 7 GetDest find 1 routes
08:30:52,698378 16383 7 GetDest find maxpoz 4
08:30:52,699126 16383 7 search_nr_jonbin digit 15 first_poz -1 nr_poz 0
08:30:52,699803 16383 7 GetDest find all f tryf poz 32767 len 7 match 1 noa 3
08:30:52,701265 16383 7 GetDest find ok routes 1
08:30:52,701901 16383 7 GetDest DIRACTION poz 0x9004 idx 4 dest 0x1 type_dir 0x1
08:30:52,702700 16383 7 play_announcement Missing route play_file -> return
08:30:52,703415 16383 7 dialdig_run ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x9004
08:30:52,704177 16383 7 dialdig_run tax from tradu is 0 bpf_r_in 0 connection time is 0 min
08:30:52,704923 16383 7 get_maxproxy_in proxyin 0
08:30:52,705661 16383 7 dialdig_recmes_switch DIRACTION
08:30:52,706300 16383 7 dialdig_recmes_switch nrtrytodir 0
08:30:52,707140 16383 7 try_play_time ppu is NULL -> return 0
08:30:52,707809 16383 7 dialdig_recmes_switch NewCallJon(nr_out '5338813', id_out '7407')
08:30:52,708572 16383 7 NewCallJon route 36868
08:30:52,709352 16383 7 NewCallJon GetRoute 1
08:30:52,710034 16383 7 GetRoute route 4 mode 0 data 0
08:30:52,712203 16383 7 GetRoute route 4 mode 0 data 0 dir GSM ASR 0.000000
08:30:52,712950 16383 7 GetRoute return route 9004 ASR max 0.000000 on dir 1
08:30:52,713801 16383 7 NewCallJon global_route 0 fork_alg 0
08:30:52,714450 16383 7 NewCallJon route 9004 poz 4
08:30:52,715085 16383 7 NewCallJon dir GSM proto UNKNOWN nr_out '5338813' id_out '7407'
08:30:52,715854 16383 7 NewCallJon dir 1 translate_out 0
08:30:52,716569 16383 7 try_alloc_transcoding
08:30:52,717375 16383 7 try_alloc_transcoding 3
08:30:52,718235 16383 7 CallJonDir IP to TDM
08:30:52,719053 16383 7 CallJonDir find portB 8 ch 0 0
08:30:52,719775 00259 7 cctl_allocRTPportBIGSW find transcoding port 259
08:30:52,721244 00259 7 Port2Slot port 259 type_eq 2

08:30:52,721871 00259 7 Port2Slot WARN port 259 pid 65535
08:30:52,722489 00259 7 Port2Slot WARN return slot -108:30:52,723110 00259 7 SendSETTIMESLOT WARN VOIP port 259
08:30:52,723736 00259 7 get_port card 32 sign_card 32512 port 259
08:30:52,724368 00259 7 SetParamRTPLOCAL aPORT 32518
08:30:52,724989 00259 7 get_maxproxy_in proxyin 0
08:30:52,725640 00259 7 cctl_allocRTPportBIGSW fast_start is 1 aPT[0].ptime is 20
08:30:52,726319 00259 7 match_codec_poz match codec 8 ptime 20 vad 0
08:30:52,727010 00259 7 SetStare port 259 state old 0 new 1
08:30:52,727698 00259 7 LiveMonitorSetup
08:30:52,728317 00259 7 TxPublish port 259 state 1,rx_dialog_sub 0 sip_pid 65535
08:30:52,728957 00259 7 CallJonDir find portA 259
08:30:52,729589 00259 7 SendCall mod 1, work 0x00000023, dirname 'GSM', proto 'UNKNOWN' nrcalls 0, prtb 8 nextroute 36868
08:30:52,730932 00259 7 SendCall ALLDIGITBIT -> SendCallJon
08:30:52,731622 00259 7 SendCallJon sign 0x0 ta->RB_pi 0x0
08:30:52,732250 00259 7 SendCallJon work 51
08:30:52,732868 00259 7 SendCallJon SendMessCallTDM len 30
08:30:52,733556 00259 7 SendMessCallTDM len 30
08:30:52,734451 00259 7 SendMessCallTDM GetProcTa() 8
08:30:52,735074 00259 7 SendMessCallTDM cat1 0 type 0 proto 'CAS'
08:30:52,735758 00259 7 CopyToB_pid dnis_noa 3
08:30:52,736390 00259 7 CopyToB_pid category 10
08:30:52,737011 00259 7 CopyToB_pid ani_noa 3 pi 0 si 0
08:30:52,737635 00259 7 CopyToB_pid category 10
08:30:52,738253 00259 7 CopyToB_pid tmr 3
08:30:52,738874 00259 7 CopyToB_pid action 1 proto 1 dir 1 routedir 1 transport 0 media
08:30:52,739502 00259 7 CopyToB_pid call_info 100
08:30:52,740120 00259 7 CopyToB_pid orig_dnis '' redirect_by ''
08:30:52,741631 00259 7 SendMessCallTDM increment nrcallout[GSM] new_val 1
08:30:52,742397 00259 7 CountSysCallOut OUT SYSTEM medium_cps 0.000000 max_cps 0 MAX 0
08:30:52,743119 00259 7 CountSysCallOut OUT SYSTEM sec 1245735052 calls in this sec 1
08:30:52,743788 00259 7 CountCallOut OUT dir nr 1 name GSM medium_cps 0.000000 max_cps 0 MAX 0 calls OUT 1
08:30:52,744487 00259 7 CountCallOut OUT dir nr 1 name GSM sec 1245735052 calls in this sec 1
08:30:52,745121 00259 7 SendMessCallTDM is_user_ccs 0 -> Tx M_CALL len 30 to 8 ani_pi 0
08:30:52,745759 00259 7 FuncTabPort GSM -> SetFunc(pid 8, gsm00)
08:30:52,746386 00259 7 SendMessCallTDM global_b_pid 8
08:30:52,747058 00259 7 Set_Timer tmr 1 val 200
08:30:52,747701 00259 7 Set_Timer tmr 0 val 202
08:30:52,748334 00259 7 SendCall end
08:30:52,748951 00259 7 NewCallJon global_b_pid 8
08:30:52,749629 00259 7 NewCallJon ret 0 nrtrytodir 1 param 0
08:30:52,750962 00259 7 EndNewCallJon ret 0
08:30:52,751576 00259 7 InformPortB port 259
08:30:52,752192 00259 7 InformPortB port 259
08:30:52,752815 00259 7 EndNewCallJon ret 0 find PORTB 8
08:30:52,754227 00008 8 gsm00 stare_port 0 sim_port 0
08:30:52,754881 00008 8 gsm00 M_CALL mod 1
08:30:52,755576 00008 8 GetParamM_CALL ANI '7407' DNIS '5338813'
08:30:52,756205 00008 8 gsm00 M_CALL mod 1 cat 1 b_pid 7 prtb 103 s170
08:30:52,757005 00008 8 gsm00 Digits 5338813 Ident 7407
08:30:52,757653 00008 8 GetCh flow 1 channel 2
08:30:52,758299 00008 8 SetStare port 8 state old 0 new 1
08:30:52,759017 00008 8 SendM Tx SERIAL CAS/ISDN card 1 len 3 data 30 02 00
08:30:52,759729 00008 8 LiveMonitorSetup

08:30:52,761670 00008 8 MTxPublish b_pid 7 prtb 259
08:30:52,762291 00008 8 MTxPublish b_pid prt 259
08:30:52,762907 00008 8 MTxPublish b_pid prtpid 263
08:30:52,763526 00008 8 TxPublish port 8 state 1,rx_dialog_sub 0 sip_pid 263
08:30:52,764163 00008 8 Set_Timer tmr 0 val 1
08:30:52,764891 00008 8 MTxPublish b_pid 7 prtb 259
08:30:52,765520 00008 8 MTxPublish b_pid prt 259
08:30:52,766147 00008 8 MTxPublish b_pid prtpid 263
08:30:52,766768 00008 8 TxPublish port 8 state 2,rx_dialog_sub 0 sip_pid 263
08:30:52,767 00008 217610 Tx GSM ATD5338813i;
08:30:52,768130 00008 8 translateGSMMES buffering len 14 cardISDN 3 lenbuf 0
08:30:52,768820 00008 8 translateGSMMES Tx GSM len 14 33 38 38 31 00 33 69 3b
08:30:52,769430 00008 8 Set_Timer tmr 0 val 3000
08:30:52,770102 00259 7 dialdigits ident '7407' number '5338813' id_tradu '7407' nr_tradu '5338813' route 0x9004
08:30:52,771527 00259 7 dialdigits ACK to M_CALL work 33 b_pid 8
08:30:52,772223 00259 7 dialdigits Instal(dialdest, 30000)
08:30:52,772859 00259 7 Set_Timer tmr 0 val 30000
08:30:52,773531 65535 8 RunWhileMain int in int retval 422864
08:30:52,774456 65535 212 SendM Tx SERIAL CAS/ISDN card 1 len 14 data 08 0c 00 44 35 33 33 38 38 31 00 33 69 3b
08:30:52,811758 65535 349 FuncCardUnBlock2ME1ISDN

8:30:55,358478 00259 7 GetLocalRtpIpPort
08:30:55,359097 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:30:55,359763 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:30:55,360913 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:55,361660 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:30:55,362317 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518
08:30:55,363098 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 109 proto 4
08:30:55,363727 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:55,364358 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 109 proto 4
08:30:55,364998 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:55,365634 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 109 pid_ua 1...

PROGRESS
event: inband_info
audio_addr: 172.16.1.9:32518
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000

08:30:55,366906 00259 7 SendMesPBX2SIP Tx to dest 263
08:30:55,367641 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:55,368291 00259 7 Port2Slot port 259 type_eq 2
08:30:55,368920 00259 7 Port2Slot port 8 ch 2 slot 162
08:30:55,369538 00259 7 Port2Slot slot 162 slotport[674]=259 return slot 162
08:30:55,370177 00259 7 VoipTxOnOffSlotVoip slot 162
08:30:55,371908 00259 7 msp_mgr_msg_tx rslot 162
08:30:55,372872 00259 7 XCONNECTVOIP vad 255
08:30:55,373541 00259 7 XCONNECTVOIP txrx 2
08:30:55,374291 00259 7 XCONNECTVOIP rtpproxy 0, DST_proxy 65535
08:30:55,374956 00259 7 XCONNECTVOIP VOIP_RTCP 0 VOIP_any 0 rtpudp 0
08:30:55,375787 00259 7 msp_mgr_msg_tx Rrtp 162 -sid "5e6ba664" -p 8 -m 20 32518 172.16.1.4 32520
08:30:55,376618 00259 7 dialdest M_CONNECT -> XCONNECTVOIPA()
08:30:55,377420 00259 7 dialdest Tx M_PINFO:RING to port 259
08:30:55,378083 00259 7 SendMessISDN sursa 7 dest 259 tip 130 len 2 proto 4
08:30:55,378825 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:55,379475 00259 7 SendMess2SIP src 7 dst 259 tip 130 len 2 proto 4
08:30:55,380149 00259 7 MTxPublish b_pid 8 prtb 8
08:30:55,381451 00259 7 TxPublish port 259 state 3,rx_dialog_sub 0 sip_pid 65535
08:30:55,382219 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:55,382891 00259 7 XCONNECTVOIP str_tratap->make_RTP 1 -> return
08:30:55,383519 00259 7 dialdest Instal(preanswerA, 180000)
08:30:55,384317 00259 7 Set_Timer tmr 0 val 180000
08:30:55,471622 00259 7 preanswerA WARN Unknown msg 78 -> ign
08:30:55,472318 65535 8 RunWhileMain int in int retval 121552
08:30:55,473417 65535 349 FuncCardUnBlock2ME1ISDN
08:30:55,474218 65535 349 add_ISDNcard card 16 type 1 dsp -1
08:30:55,474853 65535 349 add_ISDNcard card 16 type 1 chfl 50
08:30:55,475484 65535 349 GetJonDirDMaHDLC dir 9 eq 0
08:30:55,476126 65535 349 FindDSPISDNFromVoIP card 16 portvoip 65535
08:30:55,476754 65535 349 start_ISDN card 16 type 1 port 0
08:30:55,481320 65535 349 FuncCardUnBlock2ME1ISDN

08:30:58,495673 65535 344 analize_mes_ana Rx SERIAL ISDN adr 8 tip 78 len 4 data 61 78 03 00 4f 4b
08:30:58,496309 65535 344 analize_mes_ana buflng 7
08:30:58,496928 65535 344 translateINGSMMES len 3 nrpack 0 rest 3
08:30:58,497718 65535 344 analize_mes_ana buflng 6
08:30:58,498342 65535 344 CardComSetState card 1 up 1 old_stat 0
08:30:58,499097 65535 344 DispatchFromPort === 1 tip 125
08:30:58,499739 65535 344 DispatchFromPort M_PORTISDN port 8
08:30:58,500608 65535 344 DispatchFromPort MSKJON
08:30:58,501263 65535 344 DispatchFromPort P_KEYPAD dest 8 len 3 debug_run 1 debug_gsm 0 moniport 1
08:30:58,502 00008 223750 Rx GSM OK
08:30:58,502737 00008 8 gpreanswerB rx OK
08:30:58,503523 00008 8 XCONNECTVOIP TypeModulFromAddr != VOIP -> return
08:30:58,504367 00008 8 InitTimpTax rad_bill 0 tx_accounting_start 1
08:30:58,505013 00008 8 gpreanswerB OK -> ANSWER
08:30:58,505750 00008 8 MTxPublish b_pid 7 prtb 259
08:30:58,506393 00008 8 MTxPublish b_pid prt 259
08:30:58,507129 00008 8 MTxPublish b_pid prtpid 263
08:30:58,507782 00008 8 TxPublish port 8 state 4,rx_dialog_sub 0 sip_pid 263
08:30:58,508520 00259 7 preanswerA M_OFFHOOK
08:30:58,509289 00259 7 XCONNECTVOIP prt 259 prtb 8 aPORT 32518 bPORT 32520
08:30:58,509964 00259 7 XCONNECTVOIP str_tratap->make_RTP 1 -> return
08:30:58,511476 00259 7 SendTaxPortConnect r_tax 0
08:30:58,512235 00259 7 SendMessISDN sursa 7 dest 259 tip 131 len 1 proto 4
08:30:58,512885 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:58,513512 00259 7 SendMess2SIP src 7 dst 259 tip 131 len 1 proto 4
08:30:58,514313 00259 7 SendMess2SIP TxAsciMedia(), SendMessISDN(proto 4)
08:30:58,514974 00259 7 GetLocalRtpIpPort
08:30:58,515713 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:30:58,516400 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:30:58,517170 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:58,517898 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:30:58,518554 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518
08:30:58,519435 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 99 proto 4
08:30:58,520066 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:30:58,521044 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 99 proto 4
08:30:58,521685 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:30:58,522443 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 99 pid_ua 1...

ANSWER
proto: CAS
audio_addr: 172.16.1.9:32518
audio_codec: pt=8;ms=20;vad=0;rxtx=2;smap=PCMA/8000

08:30:58,523867 00259 7 SendMesPBX2SIP Tx to dest 263
08:30:58,524701 00259 7 InitTimpTax rad_bill 0 tx_accounting_start 1
08:30:58,525459 00259 7 MTxPublish b_pid 8 prtb 8
08:30:58,526129 00259 7 TxPublish port 259 state 4,rx_dialog_sub 0 sip_pid 65535
08:30:58,526771 00259 7 preanswerA GoTo(answerA)
08:30:58,533861 65535 8 S_GetTr malloc(32)
08:30:58,541133 65535 8 RunWhileMain int in int retval 50268
08:30:58,557420 65535 177 SendMesSIP2PBX Rx src 263 dst 7 len 11 ...

ANSWER_ACK

08:30:58,558969 00259 7 answerA PROXYASCII to b_pid 8
08:30:58,559872 00008 8 GetLocalRtpIpPort
08:30:58,561340 00008 8 get_pattern_addr bIP.IP 0 bIPs.IP 0 remoteID
08:30:58,561967 00008 8 get_pattern_addr WARN can't get B IP
08:30:58,562785 00008 8 GetLocalRtpIpPort b_pid 7 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 0.0.0.0 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:30:58,563464 00008 8 get_group_rtp_ip ERROR group 0 pattern 0.0.0.0, no class matched -> return 0.0.0.0
08:30:58,564240 00008 8 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 0.0.0.0:65535
08:30:58,564899 00008 8 cctl_M_PROXYASCII rez is 1(RTP proxy,transc on) ..I must send my data
08:30:58,565717 00008 8 SendMessISDN sursa 8 dest 8 tip 254 len 11 proto 1
08:30:58,566367 00008 8 SendMessISDN port 8 tip 254
08:30:58,567151 00008 8 SendMessISDN src 8 dst 8 tip 254 len 11 proto 1
08:30:58,567851 00008 8 SendMessISDN port type 0 and card type 11 != BRICARD|RGCARD -> return 387
08:30:58,581898 65535 349 FuncCardUnBlock2ME1ISDN

08:31:07,825359 65535 349 add_ISDNcard card 17 type 1 dsp -1
08:31:07,825989 65535 349 add_ISDNcard card 17 type 1 chfl 70
08:31:07,826620 65535 349 GetJonDirDMaHDLC dir 9 eq 0
08:31:07,827260 65535 349 FindDSPISDNFromVoIP card 17 portvoip 65535
08:31:07,827889 65535 349 start_ISDN card 17 type 1 port 0
08:31:07,860784 65535 347 analize_mes_ana Rx SERIAL ISDN adr 8 tip 78 len 13 data 61 78 0c 00 4e 4f 20 43 41 52 52 00 49 45 52
08:31:07,861425 65535 347 analize_mes_ana buflng 16
08:31:07,862047 65535 347 translateINGSMMES len 12 nrpack 1 rest 4
08:31:07,862832 65535 347 analize_mes_ana buflng 14
08:31:07,863450 65535 347 CardComSetState card 1 up 1 old_stat 0
08:31:07,864233 65535 347 DispatchFromPort === 1 tip 125
08:31:07,864880 65535 347 DispatchFromPort M_PORTISDN port 8
08:31:07,865621 65535 347 DispatchFromPort MSKJON
08:31:07,866267 65535 347 DispatchFromPort P_KEYPAD dest 8 len 11 debug_run 1 debug_gsm 0 moniport 1
08:31:07,867 00008 233110 Rx GSM NO CARRIER
08:31:07,867763 00008 8 answerB M_PKEYPAD -> handle_M_PKEYPAD()
08:31:07,868465 00008 8 handle_M_PKEYPAD Tx M_CANCELDN to b_pid 7, TxDisactPort()
08:31:07,869449 00008 8 get_sign_media aIP 0.0.0.0:65535
08:31:07,870149 00008 8 GetLocalRtpIpPort
08:31:07,871518 00008 8 get_pattern_addr bIP.IP 0 bIPs.IP 0 remoteID
08:31:07,872257 00008 8 get_pattern_addr WARN can't get B IP
08:31:07,872943 00008 8 GetLocalRtpIpPort b_pid 7 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 0.0.0.0 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:31:07,873621 00008 8 get_group_rtp_ip ERROR group 0 pattern 0.0.0.0, no class matched -> return 0.0.0.0
08:31:07,874431 00008 8 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 0.0.0.0:65535
08:31:07,875110 00008 8 get_sign_media sourceIP :0 sourceRTP 0.0.0.0:65535 destIP 172.16.1.9:0 destRTP 0.0.0.0:65535
08:31:07,875857 00008 8 MTxPublish b_pid 7 prtb 259
08:31:07,876502 00008 8 MTxPublish b_pid prt 259
08:31:07,877251 00008 8 MTxPublish b_pid prtpid 263
08:31:07,877901 00008 8 TxPublish port 8 state 5,rx_dialog_sub 0 sip_pid 263
08:31:07,878711 00008 8 TxDisactPort
08:31:07,879494 00008 8 UnalocDTMF WARN no voip slot alocated
08:31:07,880116 00008 8 TxDisactPort code 16
08:31:07,881272 00008 8 SendM Tx SERIAL CAS/ISDN card 1 len 2 data 38 7f
08:31:07,881967 00008 8 Port2Slot port 8 type_eq 2
08:31:07,882783 00008 8 Port2Slot port 8 ch 2 slot 162
08:31:07,883450 00008 8 Port2Slot return slot 162
08:31:07,884095 00008 8 VoipTxOnOffSlotVoip slot 162
08:31:07,884806 00008 8 msp_mgr_msg_tx rslot 162
08:31:07,885724 00008 8 UnalocCh flow 1 channel 2
08:31:07,886382 00008 8 Set_Timer tmr 0 val 1500
08:31:07,887386 00259 7 kill_rtp ta->prtd 65535 ta->e_pid 65535
08:31:07,888054 00259 7 kill_rtp Tx M_CANCELDN to prtd 65535
08:31:07,888683 00259 7 kill_rtp Tx M_RESETINFO to rtp pid 65535
08:31:07,889481 00259 7 SendMes WARN dest pid 65535 is higher than nrproc 354 -> msg not sent
08:31:07,890176 00259 7 answerA M_CANCELDN
08:31:07,892062 00259 7 update_prepaid_credit ppu (nil), OD_ppu (nil), RB_ppu (nil)
08:31:07,892986 00259 7 get_sign_media aIP 172.16.1.9:32518
08:31:07,893697 00259 7 GetLocalRtpIpPort
08:31:07,894468 00259 7 get_pattern_addr bIP.IP 40110ac bIPs.IP 30110ac remoteID
08:31:07,895148 00259 7 get_pattern_addr IP from remote media 172.16.1.4
08:31:07,895920 00259 7 GetLocalRtpIpPort b_pid 8 rtpproxy 0 SRC_audio_RTPproxyport 65535 bIPs 172.16.1.3 remoteID '' B_rtpproxy 0 B_DST_audio_RTPproxyport 65535
08:31:07,896644 00259 7 get_group_rtp_ip group 2 pattern 172.16.1.4, match rtp_ip 172.16.1.9
08:31:07,897420 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518

08:31:07,898117 00259 7 get_sign_media sourceIP 172.16.1.3:10005 sourceRTP 172.16.1.4:32520 destIP 172.16.1.9:0 destRTP 172.16.1.9:32518
08:31:07,898757 00259 7 MTxPublish b_pid 8 prtb 8
08:31:07,899556 00259 7 TxPublish port 259 state 5,rx_dialog_sub 0 sip_pid 65535
08:31:07,901205 00259 7 do_tax tip 'C' duration 9 finalize 'BOK' sid 5e6ba664
08:31:07,901844 00259 7 do_tax SIGN_s 172.16.1.3:10005 RTP_s 172.16.1.4:32520 SIGN_d 172.16.1.9:0 RTP_d 172.16.1.9:32518
08:31:07,903204 00259 7 TxDisactPort
08:31:07,903945 00259 7 UnalocDTMF WARN no voip slot alocated
08:31:07,904597 00259 7 TxDisactPort code 16
08:31:07,905224 00259 7 Port2Slot port 8 type_eq 2
08:31:07,905968 00259 7 Port2Slot WARN return slot -108:31:07,906614 00259 7 SendRESTIMESLOT WARN mombasa and VoIP port -> return
08:31:07,907363 00259 7 SendMessISDN sursa 7 dest 259 tip 134 len 1 proto 4
08:31:07,908021 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:31:07,908643 00259 7 SendMess2SIP src 7 dst 259 tip 134 len 1 proto 4
08:31:07,909514 00259 7 SendMess2SIP nex 7 mod 0 mcpsin 0.000000 curcpsin 1
08:31:07,910916 00259 7 SendMessISDN sursa 7 dest 259 tip 254 len 18 proto 4
08:31:07,911582 00259 7 SendMessISDN proto SIP -> SendMes2SIP()
08:31:07,912326 00259 7 SendMess2SIP src 7 dst 259 tip 254 len 18 proto 4
08:31:07,912985 00259 7 SendMess2SIP SendMesPBX2SIP(dest 263)
08:31:07,913617 00259 7 SendMesPBX2SIP Tx src 7 dst 263 len 18 pid_ua 1...

RELEASE
cause: 16

08:31:07,915040 00259 7 SendMesPBX2SIP Tx to dest 263
08:31:07,915815 00259 7 Set_Timer tmr 0 val 6000
08:31:07,920742 65535 8 SendMesSIP2PBX Rx src 263 dst 7 len 17 ...

RELEASE_COMPLETE

вот и получается что атс открывает rtp 32520, а gayeway ругнулась что rtp не найден и открывает свой 32518
строка 08:31:07,897420 00259 7 GetLocalRtpIpPort port<NRPORTURI, RTPP no, Tx port RTP IP 172.16.1.9:32518

еще нашел строку do_tax SIGN_s 172.16.1.3:10005 RTP_s 172.16.1.4:32520 SIGN_d 172.16.1.9:0 RTP_d 172.16.1.9:32518
это gateway сигнальный линк не открывает?



Редактировано 1 раз. Последний раз 23.06.2009 16:07 пользователем error.

то, что сторона А и В использует разные порты RTP - нормальное явление. откуда каждая из сторон знает свободен ли предлагаемый порт на применой стороне?
сигналинг SIP ходит на порт 5060 (по умолчанию). приведу здесь все таки пример трассировки звонка по SIP, правда через внутренний прокси станции на сетевого абонента. используемые на этой доске шрифты - непропорциональные, так что картинка слегка исказится. скопируйте в текстовой редактор и выберите пропорциональный шрифт (например Courier New) и картинка станет весьма наглядной. и номера портов видно, и адреса устройств...
|Time | 10.65.32.22 | 10.65.32.25 | 10.65.32.21 |
|32,669 | INVITE SDP ( g723 g711A g711U telephone-event) | |SIP From: sip:68300@10.65.32.22 To:sip:113@10.65.32.22
| |(5060) ------------------> (5060) | |
|32,670 | 100 Trying| | |SIP Status
| |(5060) <------------------ (5060) | |
|33,049 | 180 Ringing | |SIP Status
| |(5060) <------------------ (5060) | |
|35,788 | 200 OK SDP ( g711A telephone-event) | |SIP Status
| |(5060) <------------------ (5060) | |
|35,804 | | RTP (g711A) |RTP Num packets:361 Duration:7.200s SSRC:0x32D8FC80
| | |(49158) ------------------> (32712) |
|35,919 | ACK | | |SIP Request
| |(5060) ------------------> (5060) | |
|36,100 | | RTP (g711A) |RTP Num packets:361 Duration:7.199s SSRC:0xEA4170F0
| | |(49158) <------------------ (32712) |
|43,203 | BYE | | |SIP Request
| |(5060) <------------------ (5060) | |
|43,293 | 200 OK | | |SIP Status
| |(5060) ------------------> (5060) | |

++++++++++++++++++
Anything's possible. Keep thinking.

по поводу RTP действительно сглупил, так и есть откуда каждая сторона должна знать что конкретный RTP свободен или нет

при каждых телодвижениях (вызов, подняли трубку, положили) фигурирует порт 10005

снял трасировку звонка на softphone X-pro

пошел вызов на с 7407 на softphone 2000
----------------------------------------------------
RECEIVE << 172.16.1.3:10005
INVITE sip:2000@172.16.1.50;user=phone SIP/2.0
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
P-Asserted-Identity: "7407" <sip:7407@172.16.1.3>
Content-Type: application/sdp
To: <sip:2000@172.16.1.50;user=phone>
From: "7407" <sip:7407@172.16.1.3>;tag=1e94dd59e4b52af30adf21781032b763
Contact: sip:172.16.1.3
Call-ID: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737882 INVITE
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKcad03156b1f9a9e7ae98621fa672e5a0
Max-Forwards: 70
Content-Length: 365

v=0
o=OXE 1245822522 1245822522 IN IP4 172.16.1.3
s=abs
c=IN IP4 172.16.1.4
t=0 0
m=audio 32528 RTP/AVP 8 0 18 4 97
a=sendrecv
a=rtpmap:8 PCMA/8000
a=ptime:20
a=maxptime:30
a=rtpmap:0 PCMU/8000
a=ptime:20
a=maxptime:30
a=rtpmap:18 G729/8000
a=ptime:20
a=maxptime:40
a=rtpmap:4 G723/8000
a=ptime:30
a=maxptime:30
a=rtpmap:97 telephone-event/8000

SEND >> 172.16.1.3:5060
SIP/2.0 100 Trying
v: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKcad03156b1f9a9e7ae98621fa672e5a0
f: "7407" <sip:7407@172.16.1.3>;tag=1e94dd59e4b52af30adf21781032b763
t: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
m: <sip:2000@172.16.1.50:5060>
i: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737882 INVITE
Server: X-PRO build 1101
l: 0


SEND >> 172.16.1.3:5060
SIP/2.0 180 Ringing
v: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKcad03156b1f9a9e7ae98621fa672e5a0
f: "7407" <sip:7407@172.16.1.3>;tag=1e94dd59e4b52af30adf21781032b763
t: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
m: <sip:2000@172.16.1.50:5060>
i: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737882 INVITE
Server: X-PRO build 1101
l: 0


поднимаем трубку на softphone
----------------------------------------------------
SEND >> 172.16.1.3:5060
SIP/2.0 200 Ok
v: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKcad03156b1f9a9e7ae98621fa672e5a0
f: "7407" <sip:7407@172.16.1.3>;tag=1e94dd59e4b52af30adf21781032b763
t: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
m: <sip:2000@172.16.1.50:5060>
i: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737882 INVITE
c: application/sdp
Server: X-PRO build 1101
l: 131

v=0
o=2000 766062 766062 IN IP4 172.16.1.50
s=X-PRO
c=IN IP4 172.16.1.50
t=0 0
m=audio 32512 RTP/AVP 8
a=rtpmap:8 pcma/8000

RECEIVE << 172.16.1.3:10005
ACK sip:2000@172.16.1.50:5060 SIP/2.0
Contact: sip:172.16.1.3
User-Agent: ABS GW v5.1.0
To: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
From: "7407" <sip:7407@172.16.1.3>;tag=1e94dd59e4b52af30adf21781032b763
Call-ID: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737882 ACK
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bK1faf6edcc3d3a9934a195693a91db60f
Max-Forwards: 70
Content-Length: 0


ну и кладем трубку
----------------------------------------------------
RECEIVE << 172.16.1.3:10005
BYE sip:2000@172.16.1.50:5060 SIP/2.0
Supported: replaces,100rel
User-Agent: ABS GW v5.1.0
To: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
From: sip:7407@172.16.1.3;tag=1e94dd59e4b52af30adf21781032b763
Call-ID: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737883 BYE
Via: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKb950386ff5fac813f9b046aabac243d1
Max-Forwards: 70
Content-Length: 0


SEND >> 172.16.1.3:5060
SIP/2.0 200 Ok
v: SIP/2.0/UDP 172.16.1.3;branch=z9hG4bKb950386ff5fac813f9b046aabac243d1
f: <sip:7407@172.16.1.3;tag=1e94dd59e4b52af30adf21781032b763>;tag=1e94dd59e4b52af30adf21781032b763
t: <sip:2000@172.16.1.50;user=phone>;tag=2205371896
m: <sip:2000@172.16.1.50:5060>
i: cb8726c6cf4ddacba0e4e6769ebb0527@172.16.1.3
CSeq: 1556737883 BYE
Server: X-PRO build 1101
l: 0

А просто софтовым телефоном через шлюз звонки идут? Без ОХЕ?

хм, ну в снятии трассировок потренировались знатно, а к решению проблемы - не приблизились. насколько все нагляднее в wireshark и насколько туманно в motortrace...
а у вас прописана main trunk group SIP с сигнализацией ABC-F?

++++++++++++++++++
Anything's possible. Keep thinking.

софтовый телефон зарегил на ОХЕ, ну и звоню на него через ars c TG с сигнализацией isdn

тут участите строннего sip-gateway полностью исключено

поюзал wireshark и на мой взгляд хорошая штука, но без древнего хаба много что не может выудить wireshark с локальной сети
к сожалению древненго хаба нема у меня :(

Тогда надо (если есть такая возможность) запрограммировать зеркалирование трафика с порта шлюза на какой-то соседний порт.

все блин, поборол sip-gateway с помощью wireshark
надо было прописать MAC проца sip-gateway и MAC проца атс в рукопашную, софт администрирования понимает такое когда sip-gateway-е стоит проц и плата воип раздельно, но в упор не понимает когда на плате проц уже интегрирована плата воип, вот так и долго ходил вокруг да около

огромное спасибо откликнумшимся на мою проблему

на всякий случай кому будет интересно
в f5.401.32 были проблемы с ожиданием ringing c удаленной стороны (тобишь sip-gateway), на удаленной стороне формировалась ошибка no ring dial tone,
в f5.401.36 нашел устранение этой ошибки
RA crms00134609 3 3_major noFlag NEDUNURI Rama DeeptiBOISSE Danielle SIP switch-over in active state: remote extension remains in ringing

теперь стало все Ок после апгрейда до f5.401.36d