[cisco-voip] one MGCP endpoint fails to register to CCM5
Jefflin Choi
jefflin.choi at gmail.com
Fri Jul 13 01:28:01 EDT 2007
Sorry for taking this long. Been busy lately.
Here's the debug output.
Thanks,
Jeff
On 7/3/07, Ocampo, Walter <WOCAMPO at broward.org> wrote:
>
>
>
> Jefflin,
>
>
>
> Do a MGCP debug and look what is the information the port is sending to the
> call manager or if you can posted we can take a look.
>
>
>
> Regards,
>
>
>
> Walter Ocampo
>
> System Network Analyst
>
> Office of Information Technology
>
> 540 S.E 3rd. Ave, Suite 300
>
> Fort Lauderdale, FL 33301
>
> (954) 357 8041 Cell (954) 632 8228
>
> wocampo at broward.org
>
>
>
>
> From: cisco-voip-bounces at puck.nether.net
> [mailto:cisco-voip-bounces at puck.nether.net] On Behalf Of
> Jefflin Choi
> Sent: Tuesday, July 03, 2007 7:02 AM
> To: cisco-voip at puck.nether.net
> Subject: [cisco-voip] one MGCP endpoint fails to register to CCM5
>
>
>
>
>
> Hi All,
>
>
>
>
>
> I am encountering an issue where a port doesn't register on the CCM 5.0.4
> via MGCP.
>
>
> All other ports register fine without problem. Same router, same module and
> card, same configs. VIC2-4FXO card.
>
>
>
>
>
> Tried:
>
>
> No mgcp/mgcp
>
>
> reset gateway
>
>
> remove/reapply configs
>
>
>
>
>
> The router registers fine. Only one endpoint is unsuccessful in registering.
>
>
>
>
>
> Any idea or debugs/logs to enable to determine the problem.
>
>
>
>
>
> Thanks,
>
>
> Jeff
>
>
>
>
>
>
-------------- next part --------------
ig3845-14F-02#
ig3845-14F-02#
ig3845-14F-02#
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(611):[lvl=0]Enter
ed
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(355):[lvl=2]socket_set
_option returned -1.
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(365):[lvl=2]ERROR: MGC
PAPP send_mgcp_msg failure, status -1, errno 13
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(740):[lvl=0]Unabl
e to send rsip
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(754):[lvl=1]Manua
lly recroding RSIP method
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:49.157: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(276):[lvl=0]E
ntered
Jul 3 10:03:49.405: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/0/0, ch
anged state to Administrative Shutdown
Jul 3 10:03:49.405: //-1/xxxxxxxxxxxx/MGCP/mgcp_fs_rsip(2251):[lvl=0]Entered
Jul 3 10:03:49.405: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_rsip_required_and_u
pdate_rsip_info(1896):[lvl=0]restart_method=2, state=1
Jul 3 10:03:49.405: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_rsip_required_and_u
pdate_rsip_info(1941):[lvl=1] same RM as last_rsip (2), no RSIP sent
Jul 3 10:03:49.405: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_i
nfo(3311):[lvl=0]Entered
Jul 3 10:03:49.417: //-1/xxxxxxxxxxxx/MGCP/mgcp_fs_rsip(2251):[lvl=0]Entered
Jul 3 10:03:49.417: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_rsip_required_and_u
pdate_rsip_info(1896):[lvl=0]restart_method=2, state=0
Jul 3 10:03:49.417: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_rsip_required_and_u
pdate_rsip_info(1941):[lvl=1] same RM as last_rsip (2), no RSIP sent
Jul 3 10:03:49.417: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_i
nfo(3311):[lvl=0]Entered
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1913):[lvl=0]Entered
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,*next:0x00000000,trans_id:350067716,*msgp:0x66C235D4,laddr:0.0.0.0,raddr_info
:137.177.21.70,lport:2427,rport:2427,retx_cnt:0
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,retx_timer:0x673A7714,tsmax_timer:0x673A773C,firstsent_ts:512,is_restart_rsip
:0,prot_ver:4,*profile:0x64DB0AB0,wait_def_mgc_ack:0,rsip_shutdown:0,rqnt_ack:0
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,msg_type:8,last_ntfy_trans_id:0,max1_retry:5,max2_retry:7,timeout_thist:30000
,timeout_tsmax:20000,timeout_tdinit:0
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,endpt_name:aaln/S0/SU0/0,*endpt:0x66DAAB2C,notify entity:137.177.21.70
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1986):[lvl=1]max2 no o
f retries kicks in
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1460):[lvl=1
]to raddr=137.177.21.70,rport=2427 fromladdr=0.0.0.0,lport = 2427
Jul 3 10:03:49.669: MGCP Packet sent to 137.177.21.70:2427--->
RSIP 350067716 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: forced
RD: 0
<---
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1602):[lvl=1
]SUCCESSFUL retransmission
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2033):[lvl=1]retx msg
again, retx_cnt=1 timeout 1000 ms
Jul 3 10:03:49.669: MGCP Packet received from 137.177.21.70:2427--->
200 350067716
<---
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6605):[lvl=0]Entered
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(828):[lvl=2]I
nvalid parameter (pkt 0x65471938 pkt->mgcp_parm_lines 0x00000000)
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6466):[lv
l=1]endpt aaln/S0/SU0/0 was not redirected
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6677):[lvl=1]Removing m
sg : RSIP 350067716 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: forced
RD: 0
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6750):[lvl=0] Received
ACK for RSIP, setting the restart method to NONE
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(197
4):[lvl=0]Entered
Jul 3 10:03:49.669: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_i
nfo(3311):[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_reinit_ext(2160):[lvl=0]E
ntered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_fs_reinit_ext(2346):[lvl=
0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_ext_reinit(2202):[lvl=0]E
ntered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_i
nfo(3311):[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_ext_init(2169):[lvl=0]Ent
ered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_fs_rsip(2251):[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_rsip_required_and_u
pdate_rsip_info(1896):[lvl=0]restart_method=3, state=2
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_to_mgc(824):[lvl=1]re
start_method=3, rsip_shutdown=0
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_get_notified_entity(443):
[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_special_get_configured_ca(1138)
:[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_default_get_configured_ca(1086)
:[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_default_get_configured_ca(1094)
:[lvl=1]profile NE 137.177.21.70:2427
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_to_mgc(851):[lvl=2]Se
nding RSIP to (137.177.21.70)
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(611):[lvl=0]Enter
ed
Jul 3 10:03:49.741: MGCP Packet sent to 137.177.21.70:2427--->
RSIP 350067718 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: restart
<---
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(754):[lvl=1]Manua
lly recroding RSIP method
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(276):[lvl=0]E
ntered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirected(675):[lvl=0]ne
137.177.21.70:2427
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(516):
[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_set_notified_entity(540):
[lvl=1]ne 137.177.21.70:2427
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_child_redirected(921):[lv
l=0]parent aaln/S0/SU0/*, child's ne 137.177.21.70:2427
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_get_notified_entity(443):
[lvl=0]Entered
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_get_notified_entity(458):
[lvl=1]ne 137.177.21.70:2427
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_child_redirected(936):[lv
l=1]parent aaln/S0/SU0/*, child redirected to same call agent as parent
Jul 3 10:03:49.741: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_redirect_children(702):[l
vl=0]Entered
Jul 3 10:03:49.745: %LINK-3-UPDOWN: Interface Foreign Exchange Office 0/0/0, ch
anged state to up
Jul 3 10:03:49.973: MGCP Packet received from 137.177.21.70:2427--->
200 350067718
<---
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6605):[lvl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_mp_get_not_entity(828):[lvl=2]I
nvalid parameter (pkt 0x65471938 pkt->mgcp_parm_lines 0x00000000)
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_for_redirection(6466):[lv
l=1]endpt aaln/S0/SU0/0 was not redirected
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6677):[lvl=1]Removing m
sg : RSIP 350067718 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: restart
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_msg_ack(6750):[lvl=0] Received
ACK for RSIP, setting the restart method to NONE
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_and_send_pending_rsip(197
4):[lvl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_endpt_ext_and_free_rsip_i
nfo(3311):[lvl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_hostname(8675):[lvl=0]Try
ing to validate endpoint name
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_setup_per_call_data(2556):[l
vl=1]mgcpapp_setup_per_call_data: callp: 6715E938, vdbptr: 66920460, state: CALL
_IDLE
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_get_notified_entity(443):
[lvl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_get_notified_entity(458):
[lvl=1]ne 137.177.21.70:2427
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_process_mgcp_event(2686):[lv
l=1]Processing Incoming Message [RQNT 424219]
Jul 3 10:03:49.973: MGCP Packet received from 137.177.21.70:2427--->
RQNT 424219 AALN/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
X: 0
R: L/hd
Q: process,loop
<---
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_invoke_mgcp_sm(2627):[lvl=1]
Msg In-Progress(Active) [INVVERB 0], await_ev=0,queued=0x00000000
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_deferred_queue(3373):[l
vl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_store_endpt_and_ntfy_entity_nam
e(4575):[lvl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_invoke_app_sm(535):[lvl=0]MGCP:
calling FSM- callp(6715E938)
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_verify_supp_reqdet_ev(10645):[l
vl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_is_supported_ev(5437):[lvl=0](b
egin)
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_is_supported_ev(5450):[lvl=0]--
mgcp_find_evt_in_mask evtnode->package_bit_map: 40 evtnode->evt_name: 32
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_verify_supp_signal_ev(10685):[l
vl=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_request_ev(5815):[lvl=1]call
p 6715E938, voice_if 6736E280
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_disable_req_evt(1625):[lvl=0
]callp=6715E938 req_evt_list_head_p 67354B20
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/check_if_event_present_event_q(12725
):[lvl=0]event = 32
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_detect_ev(6022):[lvl=0]callp
6715E938, voice_if 6736E280
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_event_enable(1443):[lvl=0]En
tered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/check_if_event_present_event_q(12725
):[lvl=0]event = 32
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_signal_ev(5507):[lvl=0]callp
6715E938, voice_ifp 6736E280
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/process_stop_signal(1848):[lvl=0]cal
lp=6715E938, signal list=0
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_mode(6111):[
lvl=0]callp 6715E938, voice_if 6736E280
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_mode(6137):[
lvl=0]<Q:> line is present, reading values
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_mode(6183):[
lvl=1]Q mode: process=1, loop=8
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_events_need_dsp(10535):[lvl=0]E
ntered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_and_enqueue_ack(3004):[lvl
=0]Entered
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/enqueue_ack(600):[lvl=1]enqueue_ack:
ackqhead=0, ackqtail=0, ackp=65506B40, msg=6656F8C4
Jul 3 10:03:49.973: MGCP Packet sent to 137.177.21.70:2427--->
200 424219 OK
<---
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_after_ack(40
33):[lvl=0]ack_code=200
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_evt_q(6249):
[lvl=0]callp 6715E938, voice_if 6736E280, QB evt counts=0
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcp_invoke_app_sm(570):[lvl=2]MGCP:
FSM done- callp(6715E938), new state CALL_IDLE, event EV_NOTIFY_REQ
Jul 3 10:03:49.973: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_invoke_mgcp_sm(2662):[lvl=0]
return value = 0
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_hostname(8675):[lvl=0]Try
ing to validate endpoint name
Jul 3 10:03:49.981: MGCP Packet received from 137.177.21.70:2427--->
AUEP 424220 AALN/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
F: X, A, I
<---
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_audit_endpt_ack(1400):[lvl
=1]F is A for capability
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(417)
:[lvl=0]audit
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:0
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:5 bandwidth:64000, multiple:10, table_min:10, table_max:20 cn
t:0,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:6 bandwidth:64000, multiple:10, table_min:10, table_max:20 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:16 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:1 bandwidth:8000, multiple:10, table_min:10, table_max:220 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:12 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:0,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:18 bandwidth:64000, multiple:10, table_min:10, table_max:20 c
nt:0,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:16 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:2,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:1 bandwidth:8000, multiple:10, table_min:10, table_max:220 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:2,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:12 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:2,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:2,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:2,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:4
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:4,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:4,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:4,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:4,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:5
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:5,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:6
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:6,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:8
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:8,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:8,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:8,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:8,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:8,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:8,dsp_cnt:3
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:10
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:10,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:10,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:10,dsp_cnt:2
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:11
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:11,dsp_cnt:1
Jul 3 10:03:49.981: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:50.057: MGCP Packet sent to 137.177.21.70:2427--->
200 424220
I:
X: 0
L: p:10-20, a:PCMU;PCMA;G.nX64, b:64, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:
T;G;D;L;H;R;ATM;SST;PRE
L: p:10-220, a:G.729;G.729a;G.729b, b:8, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM,
v:T;G;D;L;H;R;ATM;SST;PRE
L: p:10-110, a:G.726-16;G.728, b:16, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:T
;G;D;L;H;R;ATM;SST;PRE
L: p:10-70, a:G.726-24, b:24, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:T;G;D;L;
H;R;ATM;SST;PRE
L: p:10-50, a:G.726-32, b:32, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:T;G;D;L;
H;R;ATM;SST;PRE
L: p:30-270, a:G.723.1-H;G.723;G.723.1a-H, b:6, e:on, gc:1, s:on, t:10, r:g, nt:
IN;ATM, v:T;G;D;L;H;R;ATM;SST;PRE
L: p:30-330, a:G.723.1-L;G.723.1a-L, b:5, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM
, v:T;G;D;L;H;R;ATM;SST;PRE
L: p:20-120, a:G.GSM-F, b:13, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:T;G;D;L;
H;R;ATM;SST;PRE
M: sendonly, recvonly, sendrecv, inactive, loopback, conttest, data, netwloop, n
etwtest
<---
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(611):[lvl=0]Enter
ed
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(355):[lvl=2]socket_set
_option returned -1.
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(365):[lvl=2]ERROR: MGC
PAPP send_mgcp_msg failure, status -1, errno 13
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(740):[lvl=0]Unabl
e to send rsip
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(754):[lvl=1]Manua
lly recroding RSIP method
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(276):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(611):[lvl=0]Enter
ed
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(355):[lvl=2]socket_set
_option returned -1.
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/send_mgcp_msg(365):[lvl=2]ERROR: MGC
PAPP send_mgcp_msg failure, status -1, errno 13
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(740):[lvl=0]Unabl
e to send rsip
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_rsip_msg(754):[lvl=1]Manua
lly recroding RSIP method
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_endpt_record_rsip(495):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_enq_retx_rsip_msg(276):[lvl=0]E
ntered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1913):[lvl=0]Entered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,*next:0x673A7500,trans_id:350067719,*msgp:0x67134BE8,laddr:0.0.0.0,raddr_info
:137.177.21.70,lport:2427,rport:2427,retx_cnt:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,retx_timer:0x673A7714,tsmax_timer:0x673A773C,firstsent_ts:0,is_restart_rsip:0
,prot_ver:4,*profile:0x64DB0AB0,wait_def_mgc_ack:0,rsip_shutdown:0,rqnt_ack:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,msg_type:8,last_ntfy_trans_id:0,max1_retry:5,max2_retry:7,timeout_thist:30000
,timeout_tsmax:20000,timeout_tdinit:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,endpt_name:aaln/S0/SU0/0,*endpt:0x66DAAB2C,notify entity:137.177.21.70
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1986):[lvl=1]max2 no o
f retries kicks in
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1460):[lvl=1
]to raddr=137.177.21.70,rport=2427 fromladdr=0.0.0.0,lport = 2427
Jul 3 10:03:52.941: MGCP Packet sent to 137.177.21.70:2427--->
RSIP 350067719 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: forced
RD: 0
<---
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1602):[lvl=1
]SUCCESSFUL retransmission
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2033):[lvl=1]retx msg
again, retx_cnt=1 timeout 1000 ms
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1913):[lvl=0]Entered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,*next:0x00000000,trans_id:350067721,*msgp:0x6710D54C,laddr:0.0.0.0,raddr_info
:137.177.21.70,lport:2427,rport:2427,retx_cnt:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,retx_timer:0x673A7524,tsmax_timer:0x673A754C,firstsent_ts:0,is_restart_rsip:0
,prot_ver:4,*profile:0x64DB0AB0,wait_def_mgc_ack:1,rsip_shutdown:1,rqnt_ack:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,msg_type:8,last_ntfy_trans_id:0,max1_retry:5,max2_retry:7,timeout_thist:30000
,timeout_tsmax:20000,timeout_tdinit:15
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,endpt_name:aaln/S0/SU0/0,*endpt:0x66DAAB2C,notify entity:137.177.21.70
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1986):[lvl=1]max2 no o
f retries kicks in
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1460):[lvl=1
]to raddr=137.177.21.70,rport=2427 fromladdr=0.0.0.0,lport = 2427
Jul 3 10:03:52.941: MGCP Packet sent to 137.177.21.70:2427--->
RSIP 350067721 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: restart
<---
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1602):[lvl=1
]SUCCESSFUL retransmission
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2033):[lvl=1]retx msg
again, retx_cnt=1 timeout 1000 ms
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1913):[lvl=0]Entered
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,*next:0x673A7500,trans_id:350067719,*msgp:0x67134BE8,laddr:0.0.0.0,raddr_info
:137.177.21.70,lport:2427,rport:2427,retx_cnt:1
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,retx_timer:0x673A7714,tsmax_timer:0x673A773C,firstsent_ts:0,is_restart_rsip:0
,prot_ver:4,*profile:0x64DB0AB0,wait_def_mgc_ack:0,rsip_shutdown:0,rqnt_ack:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,msg_type:8,last_ntfy_trans_id:0,max1_retry:5,max2_retry:7,timeout_thist:30000
,timeout_tsmax:20000,timeout_tdinit:0
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,endpt_name:aaln/S0/SU0/0,*endpt:0x66DAAB2C,notify entity:137.177.21.70
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1986):[lvl=1]max2 no o
f retries kicks in
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1460):[lvl=1
]to raddr=137.177.21.70,rport=2427 fromladdr=0.0.0.0,lport = 2427
Jul 3 10:03:52.941: MGCP Packet sent to 137.177.21.70:2427--->
RSIP 350067719 aaln/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
RM: forced
RD: 0
<---
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_piggyback_msg(1602):[lvl=1
]SUCCESSFUL retransmission
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(2033):[lvl=1]retx msg
again, retx_cnt=2 timeout 2000 ms
Jul 3 10:03:52.941: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1913):[lvl=0]Entered
Jul 3 10:03:52.945: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,*next:0x00000000,trans_id:350067721,*msgp:0x6710D54C,laddr:0.0.0.0,raddr_info
:137.177.21.70,lport:2427,rport:2427,retx_cnt:1
Jul 3 10:03:52.945: //-1/xxxxxxxxxxxx/MGCP/mgcp_retx_msg(1932):[lvl=0]retx_recp
->,retx_timer:0x673A7524,tsmax_timer:0x673A754C,firstsent_ts:4,is_restart_rsip:0
,prot_ver:4,*profile:0x64DB0AB0,wait_def_mgc_ack:1,rsip_shutdown:1,rqnt_ack:0
Jul 3 10:03:53.357: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_after_ack(40
33):[lvl=0]ack_code=200
Jul 3 10:03:53.357: //-1/xxxxxxxxxxxx/MGCP/mgcp_process_quarantine_evt_q(6249):
[lvl=0]callp 6715E938, voice_if 6736E280, QB evt counts=0
Jul 3 10:03:53.357: //-1/xxxxxxxxxxxx/MGCP/mgcp_invoke_app_sm(570):[lvl=2]MGCP:
FSM done- callp(6715E938), new state CALL_IDLE, event EV_NOTIFY_REQ
Jul 3 10:03:53.357: //-1/xxxxxxxxxxxx/MGCP/mgcpapp_invoke_mgcp_sm(2662):[lvl=0]
return value = 0
Jul 3 10:03:53.997: //-1/xxxxxxxxxxxx/MGCP/mgcp_check_hostname(8675):[lvl=0]Try
ing to validate endpoint name
Jul 3 10:03:53.997: MGCP Packet received from 137.177.21.70:2427--->
AUEP 424222 AALN/S0/SU0/0 at ig3845-14F-02.inscos.com MGCP 0.1
F: X, A, I
<---
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_send_audit_endpt_ack(1400):[lvl
=1]F is A for capability
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(417)
:[lvl=0]audit
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:0
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:5 bandwidth:64000, multiple:10, table_min:10, table_max:20 cn
t:0,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:6 bandwidth:64000, multiple:10, table_min:10, table_max:20 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:16 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:1 bandwidth:8000, multiple:10, table_min:10, table_max:220 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:12 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:0,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:18 bandwidth:64000, multiple:10, table_min:10, table_max:20 c
nt:0,dsp_cnt:3
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:16 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:2,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:1 bandwidth:8000, multiple:10, table_min:10, table_max:220 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:2,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:12 bandwidth:8000, multiple:10, table_min:10, table_max:220 c
nt:2,dsp_cnt:3
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:2,dsp_cnt:3
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:2,dsp_cnt:3
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:4
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:2 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:4,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:4,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:4,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:7 bandwidth:16000, multiple:10, table_min:10, table_max:110 c
nt:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:4,dsp_cnt:2
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:5
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:3 bandwidth:24000, multiple:10, table_min:10, table_max:70 cn
t:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:4 bandwidth:32000, multiple:10, table_min:10, table_max:50 cn
t:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:8 bandwidth:6400, multiple:30, table_min:30, table_max:270 cn
t:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:9 bandwidth:5400, multiple:30, table_min:30, table_max:330 cn
t:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:10 bandwidth:13200, multiple:20, table_min:20, table_max:120
cnt:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:14 bandwidth:6400, multiple:30, table_min:30, table_max:270 c
nt:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(654)
:[lvl=0]voip_codec:15 bandwidth:5400, multiple:30, table_min:30, table_max:330 c
nt:5,dsp_cnt:1
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(737)
:[lvl=0]call mgcp_add_list()
Jul 3 10:03:54.009: //-1/xxxxxxxxxxxx/MGCP/mgcp_set_local_opt_capabilities(596)
:[lvl=0]codec loop cnt:6g, nt:IN;ATM, v:T;G;D;L;H;R;ATM;SST;PRE
L: p:30-330, a:G.723.1-L;G.723.1a-L, b:5, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM
, v:T;G;D;L;H;R;ATM;SST;PRE
L: p:20-120, a:G.GSM-F, b:13, e:on, gc:1, s:on, t:10, r:g, nt:IN;ATM, v:T;G;D;L;
H;R;ATM;SST;PRE
M: sendonly, recvonly, sendrecv, inactive, loopback, conttest, data, netwloop, n
etwtest
<---
More information about the cisco-voip
mailing list