Slow connecting to Outbound Calls

i am using FreePBX Distro 32bit, Asterisk 11.5.0 and FreePBX 12.0.36
Connecting outbound calls are slow. From CLI, i can see that it takes 9 seconds before i can see that this message below. How can i make it real-time as soon as i click # on Yealink IP Phone?

  • Called SIP/DIDLogic/
    – SIP/DIDLogic-00000085 is ringing
    – SIP/DIDLogic-00000085 is making progress passing it to SIP/-00000084

Do you have the 9 second delay when you call from extension to extension?

there isn’t any delay in calling extension. I can see in CLI the ring starts immediately.

OK. That would point to a problem with your trunk or trunk config. Post your trunk config as well as a bit more detail in the logs.

here are the trunk details:

FLOWROUTE
type=friend
qualify=yes
secret=passwordhere
username=accountNumberhere
host=sip.flowroute.com
dtmfmode=rfc2833
context=from-trunk
canreinvite=no
allow=ulaw
;allow=g729 ;uncomment this line if you have G.729 licenses installed.
insecure=port,invite
fromdomain=sip.flowroute.com

ANVEO DIRECT
type=peer
host=sbc.anveo.com
port=5060
insecure=port,invite
canreinvite=yes
disallow=all
allow=ulaw
qualify=yes

Where can i extract the logs?

Asterisk log: /var/log/asterisk/full

here’s a call to GoDaddy toll free. call connected within 1~2 seconds

[2015-02-05 22:34:07] VERBOSE[2106][C-00000121] netsock2.c: == Using SIP VIDEO TOS bits 136
[2015-02-05 22:34:07] VERBOSE[2106][C-00000121] netsock2.c: == Using SIP VIDEO CoS mark 6
[2015-02-05 22:34:07] VERBOSE[2106][C-00000121] netsock2.c: == Using SIP RTP TOS bits 184
[2015-02-05 22:34:07] VERBOSE[2106][C-00000121] netsock2.c: == Using SIP RTP CoS mark 5

[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@from-internal:1] Macro(“SIP/701-000002b7”, “user-callerid,LIMIT”) in new stack

[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/701-000002b7”, “TOUCH_MONITOR=1423146847.981”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/701-000002b7”, “AMPUSER=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/701-000002b7”, “0?report”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/701-000002b7”, “1?Set(REALCALLERIDNUM=701)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/701-000002b7”, “AMPUSER=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/701-000002b7”, “0?limit”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/701-000002b7”, “AMPUSERCIDNAME=AK T28”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/701-000002b7”, “0?report”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/701-000002b7”, “AMPUSERCID=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/701-000002b7”, “__DIAL_OPTIONS=Ttr”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/701-000002b7”, “CALLERID(all)=“AK T28” <701>”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/701-000002b7”, “0?limit”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/701-000002b7”, “1?Set(GROUP(concurrency_limit)=701)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf(“SIP/701-000002b7”, “7?sub-ccss,s,1(from-internal,18669381119)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-ccss:1] ExecIf(“SIP/701-000002b7”, “0?Return()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-ccss:2] Set(“SIP/701-000002b7”, “CCSS_SETUP=TRUE”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-ccss:3] GosubIf(“SIP/701-000002b7”, “0?monitor_config,1(from-internal,18669381119):monitor_default,1(from-internal,18669381119)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/701-000002b7”, “0?is_exten”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [monitor_default@sub-ccss:2] StackPop(“SIP/701-000002b7”, “”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [monitor_default@sub-ccss:3] Return(“SIP/701-000002b7”, “FALSE”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:15] ExecIf(“SIP/701-000002b7”, “0?Set(CHANNEL(language)=)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“SIP/701-000002b7”, “1?continue”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-user-callerid,s,30)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/701-000002b7”, “CALLERID(number)=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/701-000002b7”, “CALLERID(name)=AK T28”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:32] Set(“SIP/701-000002b7”, “CDR(cnum)=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:33] Set(“SIP/701-000002b7”, “CDR(cnam)=AK T28”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:34] Set(“SIP/701-000002b7”, “CHANNEL(language)=en”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-user-callerid:35] GosubIf(“SIP/701-000002b7”, “0?app-check-classofservce,s,1()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@from-internal:2] Set(“SIP/701-000002b7”, “ROUTEUSER=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@from-internal:3] GotoIf(“SIP/701-000002b7”, “1?restrictedroute-4,18669381119,2:outbound-allroutes,18669381119,2”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (restrictedroute-4,18669381119,2)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@restrictedroute-4:2] Gosub(“SIP/701-000002b7”, “sub-record-check,s,1(out,18669381119,dontcare)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/701-000002b7”, “0?initialized”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:2] Set(“SIP/701-000002b7”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/701-000002b7”, “NOW=1423146847”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:4] Set(“SIP/701-000002b7”, “__DAY=05”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:5] Set(“SIP/701-000002b7”, “__MONTH=02”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/701-000002b7”, “__YEAR=2015”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/701-000002b7”, “__TIMESTR=20150205-223407”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:8] Set(“SIP/701-000002b7”, “__FROMEXTEN=701”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/701-000002b7”, “__MON_FMT=wav”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/701-000002b7”, “Recordings initialized”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/701-000002b7”, “0?Set(ARG3=dontcare)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/701-000002b7”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“SIP/701-000002b7”, “3?checkaction”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (sub-record-check,s,16)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-record-check:16] GotoIf(“SIP/701-000002b7”, “1?sub-record-check,out,1”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (sub-record-check,out,1)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:1] NoOp(“SIP/701-000002b7”, “Outbound Recording Check from 701 to 18669381119”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:2] Set(“SIP/701-000002b7”, “RECMODE=yes”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:3] ExecIf(“SIP/701-000002b7”, “0?Goto(routewins)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:4] ExecIf(“SIP/701-000002b7”, “0?Goto(routewins)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:5] Gosub(“SIP/701-000002b7”, “recordcheck,1(yes,out,18669381119)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/701-000002b7”, “Starting recording check against yes”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/701-000002b7”, “yes”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (sub-record-check,recordcheck,9)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:9] ExecIf(“SIP/701-000002b7”, “0?Return()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:10] Set(“SIP/701-000002b7”, “__REC_POLICY_MODE=YES”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:11] Goto(“SIP/701-000002b7”, “startrec”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (sub-record-check,recordcheck,16)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:16] NoOp(“SIP/701-000002b7”, “Starting recording: out, 18669381119”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:17] Set(“SIP/701-000002b7”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:18] Set(“SIP/701-000002b7”, “__CALLFILENAME=out-18669381119-701-20150205-223407-1423146847.981”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:19] MixMonitor(“SIP/701-000002b7”, “2015/02/05/out-18669381119-701-20150205-223407-1423146847.981.wav,ai(LOCAL_MIXMON_ID),”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:20] Set(“SIP/701-000002b7”, “__MIXMON_ID=0x98e39f0”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:21] Set(“SIP/701-000002b7”, “__RECORD_ID=SIP/701-000002b7”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:22] Set(“SIP/701-000002b7”, “__REC_STATUS=RECORDING”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:23] Set(“SIP/701-000002b7”, “CDR(recordingfile)=out-18669381119-701-20150205-223407-1423146847.981.wav”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [recordcheck@sub-record-check:24] Return(“SIP/701-000002b7”, “”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [out@sub-record-check:6] Return(“SIP/701-000002b7”, “”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@restrictedroute-4:3] ExecIf(“SIP/701-000002b7”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@restrictedroute-4:4] Set(“SIP/701-000002b7”, “MOHCLASS=default”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@restrictedroute-4:5] Set(“SIP/701-000002b7”, “_NODEST=”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [18669381119@restrictedroute-4:6] Macro(“SIP/701-000002b7”, “dialout-trunk,2,18669381119,off”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/701-000002b7”, “DIAL_TRUNK=2”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/701-000002b7”, “0?sub-pincheck,s,1()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/701-000002b7”, “0?disabletrunk,1”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/701-000002b7”, “DIAL_NUMBER=18669381119”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/701-000002b7”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/701-000002b7”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/701-000002b7”, “1?nomax”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/701-000002b7”, “0?skipoutcid”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/701-000002b7”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/701-000002b7”, “outbound-callerid,2”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/701-000002b7”, “0?Set(CALLERPRES()=)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/701-000002b7”, “0?Set(REALCALLERIDNUM=701)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/701-000002b7”, “1?normcid”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/701-000002b7”, “USEROUTCID=16469893456”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/701-000002b7”, “EMERGENCYCID=”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/701-000002b7”, “TRUNKOUTCID=”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/701-000002b7”, “1?trunkcid”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/701-000002b7”, “0?Set(CALLERID(all)=)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/701-000002b7”, “1?Set(CALLERID(all)=16469893456)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/701-000002b7”, “0?Set(CALLERID(all)=)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/701-000002b7”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/701-000002b7”, “CDR(outbound_cnum)=16469893456”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/701-000002b7”, “CDR(outbound_cnam)=”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/701-000002b7”, “1?sub-flp-2,s,1()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-flp-2:1] ExecIf(“SIP/701-000002b7”, “0?Set(TARGET_FLP_2=6518669381119)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-flp-2:2] GotoIf(“SIP/701-000002b7”, “0?match”) in new stack
[2015-02-05 22:34:07] VERBOSE[18516][C-00000121] app_mixmonitor.c: == Begin MixMonitor Recording SIP/701-000002b7
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@sub-flp-2:3] ExecIf(“SIP/701-000002b7”, “1?Return()”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/701-000002b7”, “OUTNUM=18669381119”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/701-000002b7”, “custom=SIP/Anveo”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/701-000002b7”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/701-000002b7”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/701-000002b7”, “dialout-trunk-predial-hook,”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] GotoIf(“SIP/701-000002b7”, “0?custom-freepbx-a2billing,18669381119,1:2”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-dialout-trunk-predial-hook,s,2)
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:2] MacroExit(“SIP/701-000002b7”, “”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/701-000002b7”, “0?bypass,1”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/701-000002b7”, “1?Set(CONNECTEDLINE(num,i)=18669381119)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/701-000002b7”, “1?Set(CONNECTEDLINE(name,i)=CID:16469893456)”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/701-000002b7”, “0?customtrunk”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/701-000002b7”, “SIP/Anveo/18669381119,300,Tt”) in new stack
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] netsock2.c: == Using SIP RTP TOS bits 184
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] netsock2.c: == Using SIP RTP CoS mark 5
[2015-02-05 22:34:07] VERBOSE[18515][C-00000121] app_dial.c: – Called SIP/Anveo/18669381119

[2015-02-05 22:34:09] VERBOSE[18515][C-00000121] app_dial.c: – SIP/Anveo-000002b8 is making progress passing it to SIP/701-000002b7

[2015-02-05 22:34:10] VERBOSE[18515][C-00000121] app_dial.c: – SIP/Anveo-000002b8 answered SIP/701-000002b7
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“SIP/701-000002b7”, “hangupcall,”) in new stack
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/701-000002b7”, “1?theend”) in new stack
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: – Goto (macro-hangupcall,s,3)
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/701-000002b7”, “0?Set(CDR(recordingfile)=)”) in new stack
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/701-000002b7”, “”) in new stack
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/701-000002b7’ in macro ‘hangupcall’
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/701-000002b7’
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/701-000002b7’ in macro ‘dialout-trunk’
[2015-02-05 22:35:19] VERBOSE[18515][C-00000121] pbx.c: == Spawn extension (restrictedroute-4, 18669381119, 6) exited non-zero on ‘SIP/701-000002b7’
[2015-02-05 22:35:19] VERBOSE[18516][C-00000121] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-02-05 22:35:19] VERBOSE[18516][C-00000121] app_mixmonitor.c: == End MixMonitor Recording SIP/701-000002b7

here to a local mobile number which took 7~8 to hear a ring tone.

dial number and press #
2015-02-05 22:50:07] VERBOSE[2106][C-00000125] netsock2.c: == Using SIP VIDEO TOS bits 136
[2015-02-05 22:50:07] VERBOSE[2106][C-00000125] netsock2.c: == Using SIP VIDEO CoS mark 6
[2015-02-05 22:50:07] VERBOSE[2106][C-00000125] netsock2.c: == Using SIP RTP TOS bits 184
[2015-02-05 22:50:07] VERBOSE[2106][C-00000125] netsock2.c: == Using SIP RTP CoS mark 5

[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@from-internal:1] Macro(“SIP/701-000002bf”, “user-callerid,LIMIT”) in new stack

[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/701-000002bf”, “TOUCH_MONITOR=1423147807.989”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/701-000002bf”, “AMPUSER=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/701-000002bf”, “0?report”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/701-000002bf”, “1?Set(REALCALLERIDNUM=701)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/701-000002bf”, “AMPUSER=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/701-000002bf”, “0?limit”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/701-000002bf”, “AMPUSERCIDNAME=AK T28”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/701-000002bf”, “0?report”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/701-000002bf”, “AMPUSERCID=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/701-000002bf”, “__DIAL_OPTIONS=Ttr”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/701-000002bf”, “CALLERID(all)=“AK T28” <701>”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/701-000002bf”, “0?limit”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/701-000002bf”, “1?Set(GROUP(concurrency_limit)=701)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf(“SIP/701-000002bf”, “7?sub-ccss,s,1(from-internal,LocalMobileNumber)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-ccss:1] ExecIf(“SIP/701-000002bf”, “0?Return()”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-ccss:2] Set(“SIP/701-000002bf”, “CCSS_SETUP=TRUE”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-ccss:3] GosubIf(“SIP/701-000002bf”, “0?monitor_config,1(from-internal,LocalMobileNumber):monitor_default,1(from-internal,LocalMobileNumber)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/701-000002bf”, “0?is_exten”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [monitor_default@sub-ccss:2] StackPop(“SIP/701-000002bf”, “”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [monitor_default@sub-ccss:3] Return(“SIP/701-000002bf”, “FALSE”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:15] ExecIf(“SIP/701-000002bf”, “0?Set(CHANNEL(language)=)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:16] GotoIf(“SIP/701-000002bf”, “1?continue”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (macro-user-callerid,s,30)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/701-000002bf”, “CALLERID(number)=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/701-000002bf”, “CALLERID(name)=AK T28”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:32] Set(“SIP/701-000002bf”, “CDR(cnum)=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:33] Set(“SIP/701-000002bf”, “CDR(cnam)=AK T28”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:34] Set(“SIP/701-000002bf”, “CHANNEL(language)=en”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-user-callerid:35] GosubIf(“SIP/701-000002bf”, “0?app-check-classofservce,s,1()”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@from-internal:2] Set(“SIP/701-000002bf”, “ROUTEUSER=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@from-internal:3] GotoIf(“SIP/701-000002bf”, “1?restrictedroute-4,LocalMobileNumber,2:outbound-allroutes,LocalMobileNumber,2”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (restrictedroute-4,LocalMobileNumber,2)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@restrictedroute-4:2] Gosub(“SIP/701-000002bf”, “sub-record-check,s,1(out,LocalMobileNumber,dontcare)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/701-000002bf”, “0?initialized”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:2] Set(“SIP/701-000002bf”, “__REC_STATUS=INITIALIZED”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/701-000002bf”, “NOW=1423147807”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:4] Set(“SIP/701-000002bf”, “__DAY=05”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:5] Set(“SIP/701-000002bf”, “__MONTH=02”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/701-000002bf”, “__YEAR=2015”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/701-000002bf”, “__TIMESTR=20150205-225007”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:8] Set(“SIP/701-000002bf”, “__FROMEXTEN=701”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/701-000002bf”, “__MON_FMT=wav”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:10] NoOp(“SIP/701-000002bf”, “Recordings initialized”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/701-000002bf”, “0?Set(ARG3=dontcare)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/701-000002bf”, “REC_POLICY_MODE_SAVE=”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“SIP/701-000002bf”, “3?checkaction”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (sub-record-check,s,16)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-record-check:16] GotoIf(“SIP/701-000002bf”, “1?sub-record-check,out,1”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (sub-record-check,out,1)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:1] NoOp(“SIP/701-000002bf”, “Outbound Recording Check from 701 to LocalMobileNumber”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:2] Set(“SIP/701-000002bf”, “RECMODE=yes”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:3] ExecIf(“SIP/701-000002bf”, “0?Goto(routewins)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:4] ExecIf(“SIP/701-000002bf”, “0?Goto(routewins)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:5] Gosub(“SIP/701-000002bf”, “recordcheck,1(yes,out,LocalMobileNumber)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:1] NoOp(“SIP/701-000002bf”, “Starting recording check against yes”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:2] Goto(“SIP/701-000002bf”, “yes”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (sub-record-check,recordcheck,9)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:9] ExecIf(“SIP/701-000002bf”, “0?Return()”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:10] Set(“SIP/701-000002bf”, “__REC_POLICY_MODE=YES”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:11] Goto(“SIP/701-000002bf”, “startrec”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (sub-record-check,recordcheck,16)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:16] NoOp(“SIP/701-000002bf”, “Starting recording: out, LocalMobileNumber”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:17] Set(“SIP/701-000002bf”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:18] Set(“SIP/701-000002bf”, “__CALLFILENAME=out-LocalMobileNumber-701-20150205-225007-1423147807.989”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:19] MixMonitor(“SIP/701-000002bf”, “2015/02/05/out-LocalMobileNumber-701-20150205-225007-1423147807.989.wav,ai(LOCAL_MIXMON_ID),”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:20] Set(“SIP/701-000002bf”, “__MIXMON_ID=0x9971760”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:21] Set(“SIP/701-000002bf”, “__RECORD_ID=SIP/701-000002bf”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:22] Set(“SIP/701-000002bf”, “__REC_STATUS=RECORDING”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:23] Set(“SIP/701-000002bf”, “CDR(recordingfile)=out-LocalMobileNumber-701-20150205-225007-1423147807.989.wav”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [recordcheck@sub-record-check:24] Return(“SIP/701-000002bf”, “”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [out@sub-record-check:6] Return(“SIP/701-000002bf”, “”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@restrictedroute-4:3] ExecIf(“SIP/701-000002bf”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@restrictedroute-4:4] Set(“SIP/701-000002bf”, “MOHCLASS=default”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@restrictedroute-4:5] Set(“SIP/701-000002bf”, “_NODEST=”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [LocalMobileNumber@restrictedroute-4:6] Macro(“SIP/701-000002bf”, “dialout-trunk,2,LocalMobileNumber,off”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/701-000002bf”, “DIAL_TRUNK=2”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/701-000002bf”, “0?sub-pincheck,s,1()”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/701-000002bf”, “0?disabletrunk,1”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/701-000002bf”, “DIAL_NUMBER=LocalMobileNumber”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/701-000002bf”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/701-000002bf”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/701-000002bf”, “1?nomax”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/701-000002bf”, “0?skipoutcid”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/701-000002bf”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/701-000002bf”, “outbound-callerid,2”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/701-000002bf”, “0?Set(CALLERPRES()=)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/701-000002bf”, “0?Set(REALCALLERIDNUM=701)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/701-000002bf”, “1?normcid”) in new stack
[2015-02-05 22:50:07] VERBOSE[19459][C-00000125] app_mixmonitor.c: == Begin MixMonitor Recording SIP/701-000002bf
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/701-000002bf”, “USEROUTCID=16469893456”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/701-000002bf”, “EMERGENCYCID=”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/701-000002bf”, “TRUNKOUTCID=”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/701-000002bf”, “1?trunkcid”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/701-000002bf”, “0?Set(CALLERID(all)=)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/701-000002bf”, “1?Set(CALLERID(all)=16469893456)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/701-000002bf”, “0?Set(CALLERID(all)=)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/701-000002bf”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/701-000002bf”, “CDR(outbound_cnum)=16469893456”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/701-000002bf”, “CDR(outbound_cnam)=”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/701-000002bf”, “1?sub-flp-2,s,1()”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-flp-2:1] ExecIf(“SIP/701-000002bf”, “1?Set(TARGET_FLP_2=65LocalMobileNumber)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-flp-2:2] GotoIf(“SIP/701-000002bf”, “1?match”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (sub-flp-2,s,5)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-flp-2:5] Set(“SIP/701-000002bf”, “DIAL_NUMBER=65LocalMobileNumber”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@sub-flp-2:6] Return(“SIP/701-000002bf”, “”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/701-000002bf”, “OUTNUM=65LocalMobileNumber”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/701-000002bf”, “custom=SIP/Anveo”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/701-000002bf”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/701-000002bf”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/701-000002bf”, “dialout-trunk-predial-hook,”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] GotoIf(“SIP/701-000002bf”, “0?custom-freepbx-a2billing,65LocalMobileNumber,1:2”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Goto (macro-dialout-trunk-predial-hook,s,2)
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:2] MacroExit(“SIP/701-000002bf”, “”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/701-000002bf”, “0?bypass,1”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/701-000002bf”, “1?Set(CONNECTEDLINE(num,i)=65LocalMobileNumber)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/701-000002bf”, “1?Set(CONNECTEDLINE(name,i)=CID:16469893456)”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/701-000002bf”, “0?customtrunk”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/701-000002bf”, “SIP/Anveo/65LocalMobileNumber,300,Tt”) in new stack
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] netsock2.c: == Using SIP RTP TOS bits 184
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] netsock2.c: == Using SIP RTP CoS mark 5
[2015-02-05 22:50:07] VERBOSE[19458][C-00000125] app_dial.c: – Called SIP/Anveo/65LocalMobileNumber

[2015-02-05 22:50:12] VERBOSE[19458][C-00000125] app_dial.c: – SIP/Anveo-000002c0 is making progress passing it to SIP/701-000002bf <—my iPhone ring 2~3 seconds later

[2015-02-05 22:50:20] VERBOSE[19458][C-00000125] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/701-000002bf’ in macro ‘dialout-trunk’
[2015-02-05 22:50:20] VERBOSE[19458][C-00000125] pbx.c: == Spawn extension (restrictedroute-4, LocalMobileNumber, 6) exited non-zero on ‘SIP/701-000002bf’
[2015-02-05 22:50:20] VERBOSE[19459][C-00000125] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-02-05 22:50:20] VERBOSE[19459][C-00000125] app_mixmonitor.c: == End MixMonitor Recording SIP/701-000002bf

this error was not in the log. i saw this in CLI -
[2015-02-05 23:44:14] WARNING[21032][C-00000141]: translate.c:343 framein: no samples for ulawtolin

wait 7~9seconds later to see the next message

-- SIP/Anveo-000002f8 is making progress passing it to SIP/701-000002f7
   > 0x9edf2a8 -- Probation passed - setting RTP source address to 209.58.46.4:23256
   > 0xb7439178 -- Probation passed - setting RTP source address to my.ip.add.ress:11788

I too encountered this 9sec delay in outbound calls.On latest distro built

i have a lot of user hanging up the phones at the 5-7th seconds. i sent in a ticket to Anveo and was told that the pbx might not send an invite immediately. any help?

is there any way that i can add a fake ring only for first 3-4 seconds?