No sound with stun server

ggirodda · September 7, 2013, 8:41am

Hi,
I use stun server for communications from one extension (from the external) to another one because the server asterisk is behind NAT; the call starts and the rtcp packet are sent and received so i haven’t any automatic hangup.
I enabled rtcp debug that shows me the data transitions in the ports detected by stun server so it works.
Where is the problem?
I use asterisk 11.04 with freepbx 2.11, stun server is on the same asterisk server (at the moment), but also with others stun server I get the same problem

Thanks, and sorry for the bad english

david55 · September 7, 2013, 9:00am

This is a discussion forum, not a support one.

If the packets are going to the address given by the STUN server, either the STUN server is broken or there is something wrong with whatever is at that address.

You have presented no evidence of an Asterisk problem (and we would require detailed logs, even if there were such a problem).

ggirodda · September 7, 2013, 9:25am

This is the first time I sent my asterisk log file,
what is the file that you want?

ggirodda · September 7, 2013, 9:32am

OK, sorry
I take the last part of the “full” file in /var/log/asterisk/
The call is terminated normally with a manual hungup

[2013-09-07 11:28:26] VERBOSE[1804][C-00000012] netsock2.c: [2013-09-07 11:28:26] VERBOSE[1804][C-00000012] netsock2.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] WARNING[4662][C-00000012] [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] WARNING[4662][C-00000012] [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] pbx.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] netsock2.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] netsock2.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] app_dial.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] app_dial.c: [2013-09-07 11:28:26] VERBOSE[4662][C-00000012] app_dial.c: [2013-09-07 11:28:28] VERBOSE[4662][C-00000012] == Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [7002@from-internal:1] Set(“SIP/7003-00000023”, “__RINGTIMER=15”) in new stack
– Executing [7002@from-internal:2] Macro(“SIP/7003-00000023”, “exten-vm,novm,7002,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/7003-00000023”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/7003-00000023”, “TOUCH_MONITOR=1378546106.36”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/7003-00000023”, “AMPUSER=7003”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/7003-00000023”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/7003-00000023”, “1?Set(REALCALLERIDNUM=7003)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/7003-00000023”, “AMPUSER=7003”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/7003-00000023”, “AMPUSERCIDNAME=Ciro Iphone Local”) in new stack
– Executing [s@macro-user-callerid:7] GotoIf(“SIP/7003-00000023”, “0?report”) in new stack
– Executing [s@macro-user-callerid:8] Set(“SIP/7003-00000023”, “AMPUSERCID=7003”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/7003-00000023”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/7003-00000023”, “CALLERID(all)=“Ciro Iphone Local” <7003>”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“SIP/7003-00000023”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:12] ExecIf(“SIP/7003-00000023”, “0?Set(GROUP(concurrency_limit)=7003)”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/7003-00000023”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:14] GosubIf(“SIP/7003-00000023”, “7?sub-ccss,s,1(macro-exten-vm,7002)”) in new stack
– Executing [s@sub-ccss:1] ExecIf(“SIP/7003-00000023”, “0?Return()”) in new stack
– Executing [s@sub-ccss:2] Set(“SIP/7003-00000023”, “CCSS_SETUP=TRUE”) in new stack
– Executing [s@sub-ccss:3] GosubIf(“SIP/7003-00000023”, “0?monitor_config,1(macro-exten-vm,7002):monitor_default,1(macro-exten-vm,7002)”) in new stack
– Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/7003-00000023”, “1?is_exten”) in new stack
– Goto (sub-ccss,monitor_default,4)
– Executing [monitor_default@sub-ccss:4] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
– Executing [monitor_default@sub-ccss:5] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
– Executing [monitor_default@sub-ccss:6] Return(“SIP/7003-00000023”, “TRUE”) in new stack
– Executing [s@sub-ccss:4] GosubIf(“SIP/7003-00000023”, “7?agent_config,1():agent_default,1()”) in new stack
– Executing [agent_config@sub-ccss:1] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
– Executing [agent_config@sub-ccss:2] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
– Executing [agent_config@sub-ccss:3] Set(“SIP/7003-00000023”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:4] Set(“SIP/7003-00000023”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:5] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
– Executing [agent_config@sub-ccss:6] ExecIf(“SIP/7003-00000023”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
– Executing [agent_config@sub-ccss:7] ExecIf(“SIP/7003-00000023”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
– Executing [agent_config@sub-ccss:8] ExecIf(“SIP/7003-00000023”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/7003_7002@from-ccss-)”) in new stack
– Executing [agent_config@sub-ccss:9] Set(“SIP/7003-00000023”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
ccss.c: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
– Executing [agent_config@sub-ccss:10] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [s@sub-ccss:5] Set(“SIP/7003-00000023”, “DB(AMPUSER/7003/ccss/last_number)=7002”) in new stack
– Executing [s@sub-ccss:6] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/7003-00000023”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:16] Set(“SIP/7003-00000023”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:17] GotoIf(“SIP/7003-00000023”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,28)
– Executing [s@macro-user-callerid:28] Set(“SIP/7003-00000023”, “CALLERID(number)=7003”) in new stack
– Executing [s@macro-user-callerid:29] Set(“SIP/7003-00000023”, “CALLERID(name)=Ciro Iphone Local”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/7003-00000023”, “CDR(cnum)=7003”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/7003-00000023”, “CDR(cnam)=Ciro Iphone Local”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/7003-00000023”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/7003-00000023”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/7003-00000023”, “__EXTTOCALL=7002”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/7003-00000023”, “__PICKUPMARK=7002”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/7003-00000023”, “RT=”) in new stack
– Executing [s@macro-exten-vm:6] Gosub(“SIP/7003-00000023”, “sub-record-check,s,1(exten,7002,)”) in new stack
– Executing [s@sub-record-check:1] Set(“SIP/7003-00000023”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:2] GotoIf(“SIP/7003-00000023”, “1?check”) in new stack
– Goto (sub-record-check,s,7)
– Executing [s@sub-record-check:7] Set(“SIP/7003-00000023”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:8] GotoIf(“SIP/7003-00000023”, “1?next”) in new stack
– Goto (sub-record-check,s,11)
– Executing [s@sub-record-check:11] ExecIf(“SIP/7003-00000023”, “0?Return()”) in new stack
– Executing [s@sub-record-check:12] ExecIf(“SIP/7003-00000023”, “0?Set(__REC_POLICY_MODE=)”) in new stack
– Executing [s@sub-record-check:13] GotoIf(“SIP/7003-00000023”, “0?exten,1”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/7003-00000023”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/7003-00000023”, “NOW=1378546106”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/7003-00000023”, “__DAY=07”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/7003-00000023”, “__MONTH=09”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/7003-00000023”, “__YEAR=2013”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/7003-00000023”, “__TIMESTR=20130907-112826”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/7003-00000023”, “__FROMEXTEN=7003”) in new stack
– Executing [s@sub-record-check:21] Set(“SIP/7003-00000023”, “__CALLFILENAME=exten-7002-7003-20130907-112826-1378546106.36”) in new stack
– Executing [s@sub-record-check:22] Goto(“SIP/7003-00000023”, “exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] GotoIf(“SIP/7003-00000023”, “0?callee”) in new stack
– Executing [exten@sub-record-check:2] Set(“SIP/7003-00000023”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:3] GotoIf(“SIP/7003-00000023”, “1?caller”) in new stack
– Goto (sub-record-check,exten,10)
– Executing [exten@sub-record-check:10] Set(“SIP/7003-00000023”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:11] GosubIf(“SIP/7003-00000023”, “0?record,1(exten,7002,7003)”) in new stack
– Executing [exten@sub-record-check:12] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [s@macro-exten-vm:7] GotoIf(“SIP/7003-00000023”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,13)
– Executing [s@macro-exten-vm:13] GosubIf(“SIP/7003-00000023”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:14] Macro(“SIP/7003-00000023”, “dial-one,Ttr,7002”) in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/7003-00000023”, “DEXTEN=7002”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/7003-00000023”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/7003-00000023”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/7003-00000023”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/7003-00000023”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/7003-00000023”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/7003-00000023”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/7003-00000023”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/7003-00000023”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,23)
– Executing [s@macro-dial-one:23] GotoIf(“SIP/7003-00000023”, “1?next3:continue”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] ExecIf(“SIP/7003-00000023”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
– Executing [s@macro-dial-one:25] GotoIf(“SIP/7003-00000023”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/7003-00000023”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/7003-00000023”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/7003-00000023”, “DEVICES=7002”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/7003-00000023”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/7003-00000023”, “0?Set(DEVICES=002)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/7003-00000023”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/7003-00000023”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/7003-00000023”, “THISDIAL=SIP/7002”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/7003-00000023”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/7003-00000023”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/7003-00000023”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/7003-00000023”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/7003-00000023”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/7003-00000023”, “THISPART2=SIP/7002”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/7003-00000023”, “0?Set(THISPART2=DAHDI/7002)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/7003-00000023”, “NEWDIAL=SIP/7002&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/7003-00000023”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/7003-00000023”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/7003-00000023”, “THISDIAL=SIP/7002”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/7003-00000023”, “DSTRING=SIP/7002&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/7003-00000023”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/7003-00000023”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/7003-00000023”, “DSTRING=SIP/7002”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/7003-00000023”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/7003-00000023”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:29] GosubIf(“SIP/7003-00000023”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“SIP/7003-00000023”, “DB(CALLTRACE/7002)=7003”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“SIP/7003-00000023”, “”) in new stack
– Executing [s@macro-dial-one:30] Set(“SIP/7003-00000023”, “D_OPTIONS=Ttr”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/7003-00000023”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/7003-00000023”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/7003-00000023”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/7003-00000023”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/7003-00000023”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/7003-00000023”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] GotoIf(“SIP/7003-00000023”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:38] GotoIf(“SIP/7003-00000023”, “0?godial”) in new stack
– Executing [s@macro-dial-one:39] Set(“SIP/7003-00000023”, “CONNECTEDLINE(name,i)=HP Web”) in new stack
– Executing [s@macro-dial-one:40] Set(“SIP/7003-00000023”, “CONNECTEDLINE(num)=7002”) in new stack
– Executing [s@macro-dial-one:41] Set(“SIP/7003-00000023”, “D_OPTIONS=TtrI”) in new stack
– Executing [s@macro-dial-one:42] Dial(“SIP/7003-00000023”, “SIP/7002,TtrI”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/7002
– Connected line update to SIP/7003-00000023 prevented.
– SIP/7002-00000024 is ringing
res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 202(Unknown)
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Received an SDES from 192.168.1.22:7103
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] app_dial.c: – Connected line update to SIP/7003-00000023 prevented.
[2013-09-07 11:28:28] VERBOSE[4662][C-00000012] app_dial.c: – SIP/7002-00000024 answered SIP/7003-00000023
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 200(Sender Report)
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: NTP timestamp: 3587543196.2083573760
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: RTP timestamp: 42008
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SPC: 273 SOC: 43680
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Packets lost so far: 0
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Highest sequence number: 0
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Sequence number cycles: 0
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Interarrival jitter: 0
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Last SR(our NTP): 0.0000000000
[2013-09-07 11:28:33] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: DLSR: 0.0000 (sec)
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0304
Their last SR: 2426159175
DLSR: 0.1940 (sec)

[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546113.2534658048
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 45120
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 282
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 45120
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 256
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:33] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 23.6340 (sec)

[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0310
Their last SR: 2426159175
DLSR: 5.1940 (sec)

[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546118.2534912000
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 84640
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 529
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 84640
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:38] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 28.6340 (sec)

[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 200(Sender Report)
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: NTP timestamp: 3587543201.0536834048
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: RTP timestamp: 85696
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SPC: 546 SOC: 87360
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Packets lost so far: 0
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Highest sequence number: 0
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Sequence number cycles: 0
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Interarrival jitter: 0
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Last SR(our NTP): 0.0000000000
[2013-09-07 11:28:38] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: DLSR: 0.0000 (sec)
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0304
Their last SR: 2426517105
DLSR: 4.7330 (sec)

[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546123.2536235008
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 124320
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 777
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 124320
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:43] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 65504.0990 (sec)

[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 200(Sender Report)
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: NTP timestamp: 3587543207.0359813120
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: RTP timestamp: 129768
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SPC: 819 SOC: 131040
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Packets lost so far: 0
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Highest sequence number: 0
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Sequence number cycles: 0
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Interarrival jitter: 0
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Last SR(our NTP): 0.0000000000
[2013-09-07 11:28:44] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: DLSR: 0.0000 (sec)
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0309
Their last SR: 2426878129
DLSR: 4.2300 (sec)

[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546128.2535968768
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 163840
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 1024
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 163840
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:48] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 65509.0990 (sec)

[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 200(Sender Report)
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: NTP timestamp: 3587543212.1033265152
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: RTP timestamp: 173392
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SPC: 1092 SOC: 174720
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Packets lost so far: 0
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Highest sequence number: 0
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Sequence number cycles: 0
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Interarrival jitter: 0
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Last SR(our NTP): 0.0000000000
[2013-09-07 11:28:49] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: DLSR: 0.0000 (sec)
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0308
Their last SR: 2427235475
DLSR: 3.7760 (sec)

[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546133.2534731776
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 204800
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 1280
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 204800
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:53] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 65514.0980 (sec)

[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c:

Got RTCP from 192.168.1.22:7103
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: PT: 200(Sender Report)
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Reception reports: 1
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SSRC of sender: 2840964710
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: NTP timestamp: 3587543218.3059662848
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: RTP timestamp: 217056
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: SPC: 1365 SOC: 218400
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Packets lost so far: 0
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Highest sequence number: 0
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Sequence number cycles: 0
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Interarrival jitter: 0
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: Last SR(our NTP): 0.0000000000
[2013-09-07 11:28:55] VERBOSE[4662][C-00000012] res_rtp_asterisk.c: DLSR: 0.0000 (sec)
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c:

Sending RTCP RR to 192.168.1.22:7103
Our SSRC: 487502373
Their SSRC: 1722701225
iFraction lost: 0
Cumulative loss: 0
IA jitter: 0.0301
Their last SR: 2427593179
DLSR: 3.3190 (sec)

[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: * Sent RTCP SR to 37.160.239.98:50351
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Our SSRC: 828381480
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Sent(NTP): 1378546138.2534027264
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Sent(RTP): 244480
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Sent packets: 1528
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Sent octets: 244480
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Report block:
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Fraction lost: 0
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Cumulative loss: 1
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: IA jitter: 0.0000
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: Their last SR: 0
[2013-09-07 11:28:58] VERBOSE[1804] res_rtp_asterisk.c: DLSR: 65519.0980 (sec)

[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: – Executing [h@macro-dial-one:1] Macro(“SIP/7003-00000023”, “hangupcall,”) in new stack
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/7003-00000023”, “1?theend”) in new stack
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/7003-00000023”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/7003-00000023”, “”) in new stack
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/7003-00000023’ in macro ‘hangupcall’
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on ‘SIP/7003-00000023’
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘SIP/7003-00000023’ in macro ‘dial-one’
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘SIP/7003-00000023’ in macro ‘exten-vm’
[2013-09-07 11:28:59] VERBOSE[4662][C-00000012] pbx.c: == Spawn extension (from-internal, 7002, 2) exited non-zero on ‘SIP/7003-00000023’

david55 · September 8, 2013, 12:09am

You seem to be using a GUI. If the problem is due to the GUIl, you need to use the support board for the GUI, not for Asterisk. However, at the moment, there is no evidence that Asterisk has a problem.

The log you would require would be the result of setting “sip set debug on”, as one would need to see the SDP from the SIP exchange. However, you first need to demonstrate why the problem is in Asterisk, when you have already said it is doing what the STUN server tells it to do.