FreePBX 13.0.143 - 12 Second Delay for outbound calls to start/initiate

I am replacing an old installation with the latest version on a new box.
I have copy/pasted everything over and all is working well except for this one issue.
When I place a call, there is a delay of ~10-12 seconds before it starts ringing…
I’m pretty sure I did the same dial plan…
This is on the same phone with same settings (a second trunk setup).
I get the same thing if I put a # at the end…
Any ideas?

And earth is spinning most of the day, usually after 8 o’clock :stuck_out_tongue: . Don’t want to be rude but without any log don’t expect to get any help. Make a call and post here what the asterisk cli shows. Just mentioning that you have a delay will not get you very far.

I added **Delay Here below to indicate where the delay is… not sure what has changed since I started playing with it but the delay is approx 6 seconds, even with hitting #.

freepbx-xunityCLI>
– Executing [416907800@from-internal:1] Macro(“PJSIP/222-00000025”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/222-00000025”, “TOUCH_MONITOR=1467322270.37”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/222-00000025”, “AMPUSER=222”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/222-00000025”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/222-00000025”, “1?Set(REALCALLERIDNUM=222)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/222-00000025”, “AMPUSER=222”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/222-00000025”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“PJSIP/222-00000025”, “AMPUSERCIDNAME=Charles”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“PJSIP/222-00000025”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“PJSIP/222-00000025”, “AMPUSERCID=222”) in new stack
– Executing [s@macro-user-callerid:10] Set(“PJSIP/222-00000025”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“PJSIP/222-00000025”, “CALLERID(all)=“Charles” <222>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“PJSIP/222-00000025”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/222-00000025”, “1?Set(GROUP(concurrency_limit)=222)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/222-00000025”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“PJSIP/222-00000025”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“PJSIP/222-00000025”, “CALLERID(number)=222”) in new stack
– Executing [s@macro-user-callerid:30] Set(“PJSIP/222-00000025”, “CALLERID(name)=Charles”) in new stack
– Executing [s@macro-user-callerid:31] Set(“PJSIP/222-00000025”, “CDR(cnum)=222”) in new stack
– Executing [s@macro-user-callerid:32] Set(“PJSIP/222-00000025”, “CDR(cnam)=Charles”) in new stack
– Executing [s@macro-user-callerid:33] Set(“PJSIP/222-00000025”, “CHANNEL(language)=en”) in new stack
– Executing [416907800@from-internal:2] Gosub(“PJSIP/222-00000025”, “sub-record-check,s,1(out,416907800,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“PJSIP/222-00000025”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“PJSIP/222-00000025”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“PJSIP/222-00000025”, “NOW=1467322270”) in new stack
– Executing [s@sub-record-check:4] Set(“PJSIP/222-00000025”, “__DAY=30”) in new stack
– Executing [s@sub-record-check:5] Set(“PJSIP/222-00000025”, “__MONTH=06”) in new stack
– Executing [s@sub-record-check:6] Set(“PJSIP/222-00000025”, “__YEAR=2016”) in new stack
– Executing [s@sub-record-check:7] Set(“PJSIP/222-00000025”, “__TIMESTR=20160630-173110”) in new stack
– Executing [s@sub-record-check:8] Set(“PJSIP/222-00000025”, “__FROMEXTEN=222”) in new stack
– Executing [s@sub-record-check:9] Set(“PJSIP/222-00000025”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“PJSIP/222-00000025”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“PJSIP/222-00000025”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“PJSIP/222-00000025”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“PJSIP/222-00000025”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“PJSIP/222-00000025”, “3?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“PJSIP/222-00000025”, “1?sub-record-check,out,1”) in new stack
– Goto (sub-record-check,out,1)
– Executing [out@sub-record-check:1] NoOp(“PJSIP/222-00000025”, “Outbound Recording Check from 222 to 416907800”) in new stack
– Executing [out@sub-record-check:2] Set(“PJSIP/222-00000025”, “RECMODE=dontcare”) in new stack
– Executing [out@sub-record-check:3] ExecIf(“PJSIP/222-00000025”, “1?Goto(routewins)”) in new stack
– Goto (sub-record-check,out,7)
– Executing [out@sub-record-check:7] Gosub(“PJSIP/222-00000025”, “recordcheck,1(dontcare,out,416907800)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/222-00000025”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/222-00000025”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“PJSIP/222-00000025”, “”) in new stack
– Executing [out@sub-record-check:8] Return(“PJSIP/222-00000025”, “”) in new stack
– Executing [416907800@from-internal:3] ExecIf(“PJSIP/222-00000025”, “0 ?Set(CDR(accountcode)=)”) in new stack
– Executing [416907800@from-internal:4] Set(“PJSIP/222-00000025”, “MOHCLASS=default”) in new stack
– Executing [416907800@from-internal:5] ExecIf(“PJSIP/222-00000025”, “1?Set(TRUNKCIDOVERRIDE=“XRI Inc” <4164695000>)”) in new stack
– Executing [416907800@from-internal:6] Set(“PJSIP/222-00000025”, “_NODEST=”) in new stack
– Executing [416907800@from-internal:7] Macro(“PJSIP/222-00000025”, “dialout-trunk,3,416907800,off”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“PJSIP/222-00000025”, “DIAL_TRUNK=3”) in new stack
– Executing [s@macro-dialout-trunk:2] GosubIf(“PJSIP/222-00000025”, “0?sub-pincheck,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:3] GotoIf(“PJSIP/222-00000025”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:4] Set(“PJSIP/222-00000025”, “DIAL_NUMBER=416907800”) in new stack
– Executing [s@macro-dialout-trunk:5] Set(“PJSIP/222-00000025”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“PJSIP/222-00000025”, “OUTBOUND_GROUP=OUT_3”) in new stack
– Executing [s@macro-dialout-trunk:7] GotoIf(“PJSIP/222-00000025”, “0?nomax”) in new stack
– Executing [s@macro-dialout-trunk:8] GotoIf(“PJSIP/222-00000025”, “0?chanfull”) in new stack
– Executing [s@macro-dialout-trunk:9] GotoIf(“PJSIP/222-00000025”, “0?skipoutcid”) in new stack
– Executing [s@macro-dialout-trunk:10] Set(“PJSIP/222-00000025”, “DIAL_TRUNK_OPTIONS=T”) in new stack
– Executing [s@macro-dialout-trunk:11] Macro(“PJSIP/222-00000025”, “outbound-callerid,3”) in new stack
– Executing [s@macro-outbound-callerid:1] ExecIf(“PJSIP/222-00000025”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:2] ExecIf(“PJSIP/222-00000025”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:3] ExecIf(“PJSIP/222-00000025”, “0?Set(REALCALLERIDNUM=222)”) in new stack
– Executing [s@macro-outbound-callerid:4] GotoIf(“PJSIP/222-00000025”, “1?normcid”) in new stack
– Goto (macro-outbound-callerid,s,7)
– Executing [s@macro-outbound-callerid:7] Set(“PJSIP/222-00000025”, “USEROUTCID=”) in new stack
– Executing [s@macro-outbound-callerid:8] Set(“PJSIP/222-00000025”, “EMERGENCYCID=”) in new stack
– Executing [s@macro-outbound-callerid:9] Set(“PJSIP/222-00000025”, “TRUNKOUTCID=“XRI Inc” <4164695000>”) in new stack
– Executing [s@macro-outbound-callerid:10] GotoIf(“PJSIP/222-00000025”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,15)
– Executing [s@macro-outbound-callerid:15] ExecIf(“PJSIP/222-00000025”, “1?Set(CALLERID(all)=“XRI Inc” <4164695000>)”) in new stack
– Executing [s@macro-outbound-callerid:16] ExecIf(“PJSIP/222-00000025”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:17] ExecIf(“PJSIP/222-00000025”, “1?Set(CALLERID(all)=XRI Inc <4164695000>)”) in new stack
– Executing [s@macro-outbound-callerid:18] ExecIf(“PJSIP/222-00000025”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:19] ExecIf(“PJSIP/222-00000025”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:20] Set(“PJSIP/222-00000025”, “CDR(outbound_cnum)=4164695000”) in new stack
– Executing [s@macro-outbound-callerid:21] Set(“PJSIP/222-00000025”, “CDR(outbound_cnam)=XRI Inc”) in new stack
– Executing [s@macro-dialout-trunk:12] GosubIf(“PJSIP/222-00000025”, “0?sub-flp-3,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:13] Set(“PJSIP/222-00000025”, “OUTNUM=416907800”) in new stack
– Executing [s@macro-dialout-trunk:14] Set(“PJSIP/222-00000025”, “custom=PJSIP”) in new stack
– Executing [s@macro-dialout-trunk:15] ExecIf(“PJSIP/222-00000025”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
– Executing [s@macro-dialout-trunk:16] ExecIf(“PJSIP/222-00000025”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
– Executing [s@macro-dialout-trunk:17] Macro(“PJSIP/222-00000025”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/222-00000025”, “”) in new stack
– Executing [s@macro-dialout-trunk:18] GotoIf(“PJSIP/222-00000025”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:19] ExecIf(“PJSIP/222-00000025”, “1?Set(CONNECTEDLINE(num,i)=416907800)”) in new stack
– Executing [s@macro-dialout-trunk:20] ExecIf(“PJSIP/222-00000025”, “1?Set(CONNECTEDLINE(name,i)=CID:4164695000)”) in new stack
– Executing [s@macro-dialout-trunk:21] ExecIf(“PJSIP/222-00000025”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)4164695000)”) in new stack
– Executing [s@macro-dialout-trunk:22] GotoIf(“PJSIP/222-00000025”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:23] Dial(“PJSIP/222-00000025”, “PJSIP/416907800@4164695000,300,T”) in new stack
– Called PJSIP/416907800@4164695000
DELAY HERE

– PJSIP/4164695000-00000026 answered PJSIP/222-00000025
– Channel PJSIP/4164695000-00000026 joined ‘simple_bridge’ basic-bridge <97c9ef0d-6f24-4a39-9f29-d8ee87e7d0e4>
– Channel PJSIP/222-00000025 joined ‘simple_bridge’ basic-bridge <97c9ef0d-6f24-4a39-9f29-d8ee87e7d0e4>
> 0x2c1a9b0 – Probation passed - setting RTP source address to 76.10.149.177:11908
> 0x2c1a9b0 – Probation passed - setting RTP source address to 76.10.149.177:11908
> 0x2c21560 – Probation passed - setting RTP source address to 209.217.85.206:14114
– Channel PJSIP/222-00000025 left ‘simple_bridge’ basic-bridge <97c9ef0d-6f24-4a39-9f29-d8ee87e7d0e4>
– Channel PJSIP/4164695000-00000026 left ‘simple_bridge’ basic-bridge <97c9ef0d-6f24-4a39-9f29-d8ee87e7d0e4>
== Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘PJSIP/222-00000025’ in macro ‘dialout-trunk’
== Spawn extension (from-internal, 416907800, 7) exited non-zero on ‘PJSIP/222-00000025’
– Executing [h@from-internal:1] Macro(“PJSIP/222-00000025”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/222-00000025”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/222-00000025”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] Hangup(“PJSIP/222-00000025”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/222-00000025’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/222-00000025’
freepbx-xunity*CLI>