Problem getting Follow-Me to follow me

harrisp32 · February 20, 2015, 1:51pm

My systerm is running FPBX-AsteriskNOW-12.0.36.

Hello
I have outbound and inbound calls working just fine with three different DiD’d, they have been working for over a month. But I’m trying to setup Follow-me to dial an outbound mobile phone number for one of the extensions. I’m including the # at the end of the mobile number but it will not dial out to the mobile when I call my extension from an outside line. The extension rings ok but the second number in the Follow-Me list, the external number is failing to ring.

The following shows where the call is failing but I don’t understand why its failing, since I can make outbound calls to my mobile phone and receive inbound calls from my mobile phone to my asterisk extension. Any suggestions would be very welcome.

sip_route_dump: route/path hop: sip:[email protected]
– SIP/200-00000001 is ringing
– Local/FMPR-200@from-internal-00000000;1 is ringing
– Executing [FMGL-07895000000#@from-internal:5] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1?start”) in new stack
– Goto (from-internal,FMGL-07895000000#,3)
– Executing [FMGL-07895000000#@from-internal:3] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?dodnd”) in new stack
– Executing [FMGL-07895000000#@from-internal:4] Wait(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1”) in new stack
– Executing [FMGL-07895000000#@from-internal:5] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1?start”) in new stack
– Goto (from-internal,FMGL-07895000000#,3)
– Executing [FMGL-07895000000#@from-internal:3] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?dodnd”) in new stack
– Executing [FMGL-07895000000#@from-internal:4] Wait(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1”) in new stack
– Executing [FMGL-07895000000#@from-internal:5] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1?start”) in new stack
– Goto (from-internal,FMGL-07895000000#,3)
– Executing [FMGL-07895000000#@from-internal:3] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?dodnd”) in new stack
– Executing [FMGL-07895000000#@from-internal:4] Wait(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1”) in new stack
– Executing [FMGL-07895000000#@from-internal:5] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1?start”) in new stack
– Goto (from-internal,FMGL-07895000000#,3)
– Executing [FMGL-07895000000#@from-internal:3] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?dodnd”) in new stack
– Executing [FMGL-07895000000#@from-internal:4] Wait(“Local/FMGL-07895000000#@from-internal-00000001;2”, “1”) in new stack
– Executing [FMGL-07895000000#@from-internal:5] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?start”) in new stack
– Executing [FMGL-07895000000#@from-internal:6] Set(“Local/FMGL-07895000000#@from-internal-00000001;2”, “SHARED(FM_DND,SIP/441978691000-00000000)=”) in new stack
– Executing [FMGL-07895000000#@from-internal:7] Macro(“Local/FMGL-07895000000#@from-internal-00000001;2”, “dial,15,Ttr,07895000000#”) in new stack
– Executing [s@macro-dial:1] GotoIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?dial”) in new stack
– Executing [s@macro-dial:2] Set(“Local/FMGL-07895000000#@from-internal-00000001;2”, “CHANNEL(musicclass)=default”) in new stack
– Executing [s@macro-dial:3] AGI(“Local/FMGL-07895000000#@from-internal-00000001;2”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘441978265414’ number is ‘441978265414’
> dialparties.agi: USE_CONFIRMATION: ‘FALSE’
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 07895000000# to extension map
– dialparties.agi: Extension 07895000000# cf is disabled
> dialparties.agi: extnum 07895000000# has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: Built External dialstring component for 07895000000: Local/07895000000@from-internal/n
– dialparties.agi: Filtered ARG3: 07895000000
> dialparties.agi: NODEST: 200 adding M(auto-blkvm) to dialopts: TtrM(auto-blkvm)
> dialparties.agi: NODEST: 200 blkvm enabled macro already in dialopts: TtrM(auto-blkvm)
– <Local/FMGL-07895000000#@from-internal-00000001;2>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“Local/FMGL-07895000000#@from-internal-00000001;2”, “Local/07895000000@from-internal/n,15,TtrM(auto-blkvm)”) in new stack
– Called Local/07895000000@from-internal/n
– Local/FMGL-07895000000#@from-internal-00000001;1 is ringing
– Executing [07895000000@from-internal:1] Macro(“Local/07895000000@from-internal-00000002;2”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/07895000000@from-internal-00000002;2”, “TOUCH_MONITOR=1424438512.13”) in new stack
– Executing [s@macro-user-callerid:2] Set(“Local/07895000000@from-internal-00000002;2”, “AMPUSER=441978265414”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“Local/07895000000@from-internal-00000002;2”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“Local/07895000000@from-internal-00000002;2”, “1?Set(REALCALLERIDNUM=441978265414)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“Local/07895000000@from-internal-00000002;2”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“Local/07895000000@from-internal-00000002;2”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“Local/07895000000@from-internal-00000002;2”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“Local/07895000000@from-internal-00000002;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,16)
– Executing [s@macro-user-callerid:16] GotoIf(“Local/07895000000@from-internal-00000002;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,30)
– Executing [s@macro-user-callerid:30] Set(“Local/07895000000@from-internal-00000002;2”, “CALLERID(number)=441978265414”) in new stack
– Executing [s@macro-user-callerid:31] Set(“Local/07895000000@from-internal-00000002;2”, “CALLERID(name)=441978265414”) in new stack
– Executing [s@macro-user-callerid:32] Set(“Local/07895000000@from-internal-00000002;2”, “CDR(cnum)=441978265414”) in new stack
– Executing [s@macro-user-callerid:33] Set(“Local/07895000000@from-internal-00000002;2”, “CDR(cnam)=441978265414”) in new stack
– Executing [s@macro-user-callerid:34] Set(“Local/07895000000@from-internal-00000002;2”, “CHANNEL(language)=en”) in new stack
– Executing [07895000000@from-internal:2] NoCDR(“Local/07895000000@from-internal-00000002;2”, “”) in new stack
– Executing [07895000000@from-internal:3] Progress(“Local/07895000000@from-internal-00000002;2”, “”) in new stack
– Local/07895000000@from-internal-00000002;1 is making progress passing it to Local/FMGL-07895000000#@from-internal-00000001;2
– Executing [07895000000@from-internal:4] Wait(“Local/07895000000@from-internal-00000002;2”, “1”) in new stack
– Executing [07895000000@from-internal:5] Progress(“Local/07895000000@from-internal-00000002;2”, “”) in new stack
– Local/07895000000@from-internal-00000002;1 is making progress passing it to Local/FMGL-07895000000#@from-internal-00000001;2
– Executing [07895000000@from-internal:6] Playback(“Local/07895000000@from-internal-00000002;2”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
– <Local/07895000000@from-internal-00000002;2> Playing ‘silence/1.ulaw’ (language ‘en’)
– <Local/07895000000@from-internal-00000002;2> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
– <Local/07895000000@from-internal-00000002;2> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
– Executing [07895000000@from-internal:7] Wait(“Local/07895000000@from-internal-00000002;2”, “1”) in new stack
– Executing [07895000000@from-internal:8] Congestion(“Local/07895000000@from-internal-00000002;2”, “20”) in new stack
– Local/07895000000@from-internal-00000002;1 is circuit-busy
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [s@macro-dial:8] Set(“Local/FMGL-07895000000#@from-internal-00000001;2”, “DIALSTATUS=CONGESTION”) in new stack
– Executing [s@macro-dial:9] GosubIf(“Local/FMGL-07895000000#@from-internal-00000001;2”, “0?CONGESTION,1”) in new stack
– Executing [FMGL-07895000000#@from-internal:8] Hangup(“Local/FMGL-07895000000#@from-internal-00000001;2”, “”) in new stack
== Spawn extension (from-internal, FMGL-07895000000#, 8) exited non-zero on ‘Local/FMGL-07895000000#@from-internal-00000001;2’
– Executing [h@from-internal:1] Hangup(“Local/FMGL-07895000000#@from-internal-00000001;2”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/FMGL-07895000000#@from-internal-00000001;2’
[2015-02-20 13:22:00] WARNING[2770][C-00000000]: channel.c:4816 ast_prod: Prodding channel ‘Local/07895000000@from-internal-00000002;2’ failed
== Spawn extension (from-internal, 07895000000, 8) exited non-zero on ‘Local/07895000000@from-internal-00000002;2’
– Executing [h@from-internal:1] Hangup(“Local/07895000000@from-internal-00000002;2”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/07895000000@from-internal-00000002;2’
– Nobody picked up in 20000 ms

cynjut · February 20, 2015, 2:14pm

The dialing of the number from the “from-internal” context is failing. When you call your cell phone from the local phones, do you dial the leading zero? The reason I ask is because that’s how you’ve got it working in the example above.

Whenever I want to call my cell phone from the system (especially on a follow-me, for example), I use the custom-destination (or better yet, set up a “Set Caller ID” that points to the custom destination) and go that route. It’s not quite as simple as a “follow-me”, but I find that I get better control.

harrisp32 · February 20, 2015, 2:31pm

Hi Thanks for the reply.

Yes I dial the zero, but I’ve also tried 44 the way my sip provider requires it to be sent and it still fails. It did work I remember having it setup for a short time then I disabled it again, but its broken since updates have been installed or it could be my trunk provider. Could it be the provider limiting the trunk capability??

harrisp32 · February 20, 2015, 7:16pm

This system reminds me of the dreaded windows 98, the simplest solution is to reinstall and even then you can’t be sure the problem will be fix or for how long!!!

dicko · February 20, 2015, 7:35pm

Do you have an outbound route for 07895000000 that prepends 44 (the country code for UK) and removes the initial leading 0 (the locale specific national dialing rule for the UK) so it actually dials out 447895000000 as your VSP apparently requires ?

harrisp32 · February 20, 2015, 11:51pm

Yes outbound route removes the leading zero and prepends the 44 as required. I can dial all uk numbers using the area codes 01,02, 03, 07 and 08. But I’ve even tried the FollowMe with the 44 and using the dial rule XX. That doesn’t work either. But as I say at some point it did work with the XX.

dicko · February 21, 2015, 12:20am

hehe, then go back to the backup you did when it was last working

harrisp32 · February 21, 2015, 1:26am

That amounts to a lot of time and effort I spent setting up the system being wasted, There are some bright people out there somewhere who know the answer and one day I will find out who they are.

dicko · February 21, 2015, 3:15am

True , it’s a lot of time if you have no appropriate backups (each one takes all of two minutes), but it is your time, not mine

Did you fully read the wiki because it is written by “bright people” also, it works for most everyone else . . .

harrisp32 · February 21, 2015, 11:25am

If I call the extension with the Follow Me set from another extension, the call is made/forwarded to the mobile phone. But the call is never forwarded to the mobile if the call to the extension is from an external phone.
When called from an external phone the extension rings for several seconds, then displays “Missed Call”, the external phone continues to ring, then the extension rings once again… and so on.

So thats how it was before I thought it was broken, but I never tested it using an external call into the system. Am I to conclude from this that Follow Me isn’t the kind of feature I thought it was and it is only for/works with other extensions on the system?

dicko imagine the time wasted if I’d have just taken out my backups and restored the system, I would have learned nothing but how to waste time and make more work and maybe how to restore the system to an earlier point.

reinigerjm · February 25, 2015, 7:54pm

I’m having the same issue here, it also affects Ring Groups that use external numbers. In our case, we have a Ring Group with the cell phone numbers of anyone on the alarm system call list, so that they can ring us all at once with one call. This worked when I set it up a month ago, but now it only works if the call originates from an extension in the office; it doesn’t work when an outside party dials in.

andywright · February 26, 2015, 1:58am

I’ve just tried this on a test system and it worked fine - I’ve pasted the Asterisk logs below. I called from a mobile (07961xxxxxx) to a DID (8850257) which comes in through an inbound route to extension 103. 103’s follow-me includes 0113320xxxx) which is called successfully. I’m on Freepbx 12.0.38 and Asterisk 11.16.0.

Not sure if it’s relevant but my dial options “TtrI” (“dial,20,TtrI,103-0113320xxxx#”) are different to yours (TtrM)

localhostCLI>
localhostCLI>
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [8850257@from-trunk-sip-3200257:1] Set(“SIP/3200257-000000f2”, “GROUP()=OUT_2”) in new stack
– Executing [8850257@from-trunk-sip-3200257:2] Goto(“SIP/3200257-000000f2”, “from-trunk,8850257,1”) in new stack
– Goto (from-trunk,8850257,1)
– Executing [8850257@from-trunk:1] Set(“SIP/3200257-000000f2”, “__FROM_DID=8850257”) in new stack
– Executing [8850257@from-trunk:2] Set(“SIP/3200257-000000f2”, “CIDSFSCHEME=QUxMfEFMTA==”) in new stack
– Executing [8850257@from-trunk:3] AGI(“SIP/3200257-000000f2”, “/var/www/html/admin/modules/superfecta/agi/superfecta.agi”) in new stack
– Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta is Answering the Channel
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Scheme is ALL
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The DID passed from Asterisk is: 8850257
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The number passed from Asterisk is: 07961xxxxxx
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The CID name passed from Asterisk is: unknown
/var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Executing Scheme…
– <SIP/3200257-000000f2>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
– Executing [8850257@from-trunk:4] Set(“SIP/3200257-000000f2”, “CALLERID(name)=”) in new stack
– Executing [8850257@from-trunk:5] Gosub(“SIP/3200257-000000f2”, “sub-record-check,s,1(in,8850257,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/3200257-000000f2”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/3200257-000000f2”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/3200257-000000f2”, “NOW=1424914813”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/3200257-000000f2”, “__DAY=26”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/3200257-000000f2”, “__MONTH=02”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/3200257-000000f2”, “__YEAR=2015”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/3200257-000000f2”, “__TIMESTR=20150226-014013”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/3200257-000000f2”, “__FROMEXTEN=unknown”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/3200257-000000f2”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/3200257-000000f2”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/3200257-000000f2”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/3200257-000000f2”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/3200257-000000f2”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/3200257-000000f2”, “2?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/3200257-000000f2”, “1?sub-record-check,in,1”) in new stack
– Goto (sub-record-check,in,1)
– Executing [in@sub-record-check:1] NoOp(“SIP/3200257-000000f2”, “Inbound Recording Check to 8850257”) in new stack
– Executing [in@sub-record-check:2] Set(“SIP/3200257-000000f2”, “FROMEXTEN=unknown”) in new stack
– Executing [in@sub-record-check:3] ExecIf(“SIP/3200257-000000f2”, “11?Set(FROMEXTEN=07961xxxxxx)”) in new stack
– Executing [in@sub-record-check:4] Gosub(“SIP/3200257-000000f2”, “recordcheck,1(dontcare,in,8850257)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/3200257-000000f2”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/3200257-000000f2”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [in@sub-record-check:5] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [8850257@from-trunk:6] Gosub(“SIP/3200257-000000f2”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/3200257-000000f2”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/3200257-000000f2”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [8850257@from-trunk:7] Set(“SIP/3200257-000000f2”, “CDR(did)=8850257”) in new stack
– Executing [8850257@from-trunk:8] ExecIf(“SIP/3200257-000000f2”, “1 ?Set(CALLERID(name)=07961xxxxxx)”) in new stack
– Executing [8850257@from-trunk:9] Set(“SIP/3200257-000000f2”, “CHANNEL(musicclass)=default”) in new stack
– Executing [8850257@from-trunk:10] Set(“SIP/3200257-000000f2”, “__MOHCLASS=default”) in new stack
[2015-02-26 01:40:13] WARNING[25977][C-00000074]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: ‘reversecharge’
– Executing [8850257@from-trunk:11] GotoIf(“SIP/3200257-000000f2”, “0?macro-hangupcall”) in new stack
– Executing [8850257@from-trunk:12] Set(“SIP/3200257-000000f2”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [8850257@from-trunk:13] Set(“SIP/3200257-000000f2”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [8850257@from-trunk:14] Set(“SIP/3200257-000000f2”, “__ALERT_INFO=http://localhost/;info=Test”) in new stack
– Executing [8850257@from-trunk:15] Goto(“SIP/3200257-000000f2”, “from-did-direct,103,1”) in new stack
– Goto (from-did-direct,103,1)
– Executing [103@from-did-direct:1] GotoIf(“SIP/3200257-000000f2”, “0?ext-local,103,1”) in new stack
– Executing [103@from-did-direct:2] Macro(“SIP/3200257-000000f2”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/3200257-000000f2”, “TOUCH_MONITOR=1424914809.268”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/3200257-000000f2”, “AMPUSER=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/3200257-000000f2”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/3200257-000000f2”, “1?Set(REALCALLERIDNUM=07961xxxxxx)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/3200257-000000f2”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/3200257-000000f2”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/3200257-000000f2”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/3200257-000000f2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,16)
– Executing [s@macro-user-callerid:16] GotoIf(“SIP/3200257-000000f2”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:17] Set(“SIP/3200257-000000f2”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:18] GotoIf(“SIP/3200257-000000f2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/3200257-000000f2”, “CALLERID(number)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/3200257-000000f2”, “CALLERID(name)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/3200257-000000f2”, “CDR(cnum)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/3200257-000000f2”, “CDR(cnam)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/3200257-000000f2”, “CHANNEL(language)=en_GB”) in new stack
– Executing [103@from-did-direct:3] Set(“SIP/3200257-000000f2”, “DIAL_OPTIONS=TtrI”) in new stack
– Executing [103@from-did-direct:4] Set(“SIP/3200257-000000f2”, “CONNECTEDLINE(num,i)=103”) in new stack
– Executing [103@from-did-direct:5] Gosub(“SIP/3200257-000000f2”, “sub-presencestate-display,s,1(103)”) in new stack
[2015-02-26 01:40:13] WARNING[25977][C-00000074]: func_presencestate.c:132 presence_read: PRESENCE_STATE unknown
– Executing [s@sub-presencestate-display:1] Goto(“SIP/3200257-000000f2”, “state-,1”) in new stack
– Goto (sub-presencestate-display,state-,1)
– Executing [state-@sub-presencestate-display:1] Set(“SIP/3200257-000000f2”, “PRESENCESTATE_DISPLAY=”) in new stack
– Executing [state-@sub-presencestate-display:2] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [103@from-did-direct:6] Set(“SIP/3200257-000000f2”, “CONNECTEDLINE(name)=Yealink-T48”) in new stack
– Executing [103@from-did-direct:7] Set(“SIP/3200257-000000f2”, “FM_DIALSTATUS=NOT_INUSE”) in new stack
– Executing [103@from-did-direct:8] Set(“SIP/3200257-000000f2”, “__EXTTOCALL=103”) in new stack
– Executing [103@from-did-direct:9] Set(“SIP/3200257-000000f2”, “__PICKUPMARK=103”) in new stack
– Executing [103@from-did-direct:10] Macro(“SIP/3200257-000000f2”, “blkvm-setifempty,”) in new stack
– Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/3200257-000000f2”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [s@macro-blkvm-setifempty:4] Set(“SIP/3200257-000000f2”, “__BLKVM_CHANNEL=SIP/3200257-000000f2”) in new stack
– Executing [s@macro-blkvm-setifempty:5] Set(“SIP/3200257-000000f2”, “SHARED(BLKVM,SIP/3200257-000000f2)=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:6] Set(“SIP/3200257-000000f2”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/3200257-000000f2”, “”) in new stack
– Executing [103@from-did-direct:11] GotoIf(“SIP/3200257-000000f2”, “1?skipov”) in new stack
– Goto (from-did-direct,103,14)
– Executing [103@from-did-direct:14] Set(“SIP/3200257-000000f2”, “RRNODEST=”) in new stack
– Executing [103@from-did-direct:15] Set(“SIP/3200257-000000f2”, “__NODEST=103”) in new stack
– Executing [103@from-did-direct:16] GosubIf(“SIP/3200257-000000f2”, “0?sub-fmsetcid,s,1()”) in new stack
– Executing [103@from-did-direct:17] Set(“SIP/3200257-000000f2”, “RecordMethod=Group”) in new stack
– Executing [103@from-did-direct:18] Gosub(“SIP/3200257-000000f2”, “sub-record-check,s,1(exten,103,)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/3200257-000000f2”, “11?initialized”) in new stack
– Goto (sub-record-check,s,10)
– Executing [s@sub-record-check:10] NoOp(“SIP/3200257-000000f2”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/3200257-000000f2”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/3200257-000000f2”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/3200257-000000f2”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/3200257-000000f2”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/3200257-000000f2”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“SIP/3200257-000000f2”, “Exten Recording Check between 07961xxxxxx and 103”) in new stack
– Executing [exten@sub-record-check:2] Set(“SIP/3200257-000000f2”, “CALLTYPE=external”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“SIP/3200257-000000f2”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“SIP/3200257-000000f2”, “CALLEE=dontcare”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“SIP/3200257-000000f2”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“SIP/3200257-000000f2”, “1?callee”) in new stack
– Goto (sub-record-check,exten,11)
– Executing [exten@sub-record-check:11] Gosub(“SIP/3200257-000000f2”, “recordcheck,1(dontcare,external,103)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/3200257-000000f2”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/3200257-000000f2”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [exten@sub-record-check:12] Return(“SIP/3200257-000000f2”, “”) in new stack
– Executing [103@from-did-direct:19] GotoIf(“SIP/3200257-000000f2”, “1 ?skipsimple”) in new stack
– Goto (from-did-direct,103,21)
– Executing [103@from-did-direct:21] Set(“SIP/3200257-000000f2”, “RingGroupMethod=ringall”) in new stack
– Executing [103@from-did-direct:22] Set(“SIP/3200257-000000f2”, “_FMGRP=103”) in new stack
– Executing [103@from-did-direct:23] GotoIf(“SIP/3200257-000000f2”, “0?doconfirm”) in new stack
– Executing [103@from-did-direct:24] Macro(“SIP/3200257-000000f2”, “dial,20,TtrI,103-0113320xxxx#”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/3200257-000000f2”, “0?dial”) in new stack
– Executing [s@macro-dial:2] Set(“SIP/3200257-000000f2”, “CHANNEL(musicclass)=default”) in new stack
– Executing [s@macro-dial:3] AGI(“SIP/3200257-000000f2”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘07961xxxxxx’ number is ‘07961xxxxxx’
– AGI Script Executing Application: (SIPAddHeader) Options: (Alert-Info:http://localhost/;info=Test)
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 103 to extension map
– dialparties.agi: Added extension 0113320xxxx# to extension map
– dialparties.agi: Extension 103 cf is disabled
– dialparties.agi: Extension 0113320xxxx# cf is disabled
– dialparties.agi: Extension 103 do not disturb is disabled
– dialparties.agi: dbset CALLTRACE/103 to 07961xxxxxx
– dialparties.agi: Filtered ARG3: 103-0113320xxxx
– <SIP/3200257-000000f2>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“SIP/3200257-000000f2”, “SIP/103&Local/0113320xxxx@from-internal/n,20,TtrIM(auto-blkvm)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/103
– Called Local/0113320xxxx@from-internal/n
– Connected line update to SIP/3200257-000000f2 prevented.
– Executing [0113320xxxx@from-internal:1] Macro(“Local/0113320xxxx@from-internal-0000000c;2”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “TOUCH_MONITOR=1424914813.271”) in new stack
– Executing [s@macro-user-callerid:2] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “AMPUSER=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?Set(REALCALLERIDNUM=07961xxxxxx)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,16)
– Executing [s@macro-user-callerid:16] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CALLERID(number)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:30] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CALLERID(name)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:31] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CDR(cnum)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:32] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CDR(cnam)=07961xxxxxx”) in new stack
– Executing [s@macro-user-callerid:33] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CHANNEL(language)=en_GB”) in new stack
– Executing [0113320xxxx@from-internal:2] Gosub(“Local/0113320xxxx@from-internal-0000000c;2”, “sub-record-check,s,1(out,0113320xxxx,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “NOW=1424914813”) in new stack
– Executing [s@sub-record-check:4] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__DAY=26”) in new stack
– Executing [s@sub-record-check:5] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__MONTH=02”) in new stack
– Executing [s@sub-record-check:6] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__YEAR=2015”) in new stack
– Executing [s@sub-record-check:7] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__TIMESTR=20150226-014013”) in new stack
– Executing [s@sub-record-check:8] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__FROMEXTEN=07961xxxxxx”) in new stack
– Executing [s@sub-record-check:9] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“Local/0113320xxxx@from-internal-0000000c;2”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “3?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?sub-record-check,out,1”) in new stack
– Goto (sub-record-check,out,1)
– Executing [out@sub-record-check:1] NoOp(“Local/0113320xxxx@from-internal-0000000c;2”, “Outbound Recording Check from 07961xxxxxx to 0113320xxxx”) in new stack
– Executing [out@sub-record-check:2] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “RECMODE=”) in new stack
– Executing [out@sub-record-check:3] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?Goto(routewins)”) in new stack
– Goto (sub-record-check,out,7)
– Executing [out@sub-record-check:7] Gosub(“Local/0113320xxxx@from-internal-0000000c;2”, “recordcheck,1(dontcare,out,0113320xxxx)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“Local/0113320xxxx@from-internal-0000000c;2”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“Local/0113320xxxx@from-internal-0000000c;2”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“Local/0113320xxxx@from-internal-0000000c;2”, “”) in new stack
– Executing [out@sub-record-check:8] Return(“Local/0113320xxxx@from-internal-0000000c;2”, “”) in new stack
– Executing [0113320xxxx@from-internal:3] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “MOHCLASS=default”) in new stack
– Executing [0113320xxxx@from-internal:4] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “_NODEST=”) in new stack
– Executing [0113320xxxx@from-internal:5] Macro(“Local/0113320xxxx@from-internal-0000000c;2”, “dialout-trunk,2,0113320xxxx,off”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “DIAL_TRUNK=2”) in new stack
– Executing [s@macro-dialout-trunk:2] GosubIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?sub-pincheck,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:3] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:4] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “DIAL_NUMBER=0113320xxxx”) in new stack
– Executing [s@macro-dialout-trunk:5] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “OUTBOUND_GROUP=OUT_2”) in new stack
– Executing [s@macro-dialout-trunk:7] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?nomax”) in new stack
– Goto (macro-dialout-trunk,s,9)
– Executing [s@macro-dialout-trunk:9] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?skipoutcid”) in new stack
– Executing [s@macro-dialout-trunk:10] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack
– Executing [s@macro-dialout-trunk:11] Macro(“Local/0113320xxxx@from-internal-0000000c;2”, “outbound-callerid,2”) in new stack
– Executing [s@macro-outbound-callerid:1] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?Set(CALLERPRES()=allowed_not_screened)”) in new stack
– Executing [s@macro-outbound-callerid:2] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(REALCALLERIDNUM=07961xxxxxx)”) in new stack
– Executing [s@macro-outbound-callerid:3] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?normcid”) in new stack
– Executing [s@macro-outbound-callerid:4] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “USEROUTCID=07961xxxxxx”) in new stack
– Executing [s@macro-outbound-callerid:5] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?bypass”) in new stack
– Goto (macro-outbound-callerid,s,7)
– Executing [s@macro-outbound-callerid:7] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “EMERGENCYCID=”) in new stack
– Executing [s@macro-outbound-callerid:8] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “TRUNKOUTCID=<01133200257>”) in new stack
– Executing [s@macro-outbound-callerid:9] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,14)
– Executing [s@macro-outbound-callerid:14] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?Set(CALLERID(all)=<01133200257>)”) in new stack
– Executing [s@macro-outbound-callerid:15] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “1?Set(CALLERID(all)=07961xxxxxx)”) in new stack
– Executing [s@macro-outbound-callerid:16] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:17] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:18] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CDR(outbound_cnum)=07961xxxxxx”) in new stack
– Executing [s@macro-outbound-callerid:19] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “CDR(outbound_cnam)=”) in new stack
– Executing [s@macro-dialout-trunk:12] GosubIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?sub-flp-2,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:13] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “OUTNUM=0113320xxxx”) in new stack
– Executing [s@macro-dialout-trunk:14] Set(“Local/0113320xxxx@from-internal-0000000c;2”, “custom=SIP/3200257”) in new stack
– Executing [s@macro-dialout-trunk:15] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
– Executing [s@macro-dialout-trunk:16] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
– Executing [s@macro-dialout-trunk:17] Macro(“Local/0113320xxxx@from-internal-0000000c;2”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“Local/0113320xxxx@from-internal-0000000c;2”, “”) in new stack
– Executing [s@macro-dialout-trunk:18] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:19] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(CONNECTEDLINE(num,i)=0113320xxxx)”) in new stack
– Executing [s@macro-dialout-trunk:20] ExecIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?Set(CONNECTEDLINE(name,i)=CID:07961xxxxxx)”) in new stack
– Executing [s@macro-dialout-trunk:21] GotoIf(“Local/0113320xxxx@from-internal-0000000c;2”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:22] Dial(“Local/0113320xxxx@from-internal-0000000c;2”, “SIP/3200257/0113320xxxx,300,Tt”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/3200257/0113320xxxx
– SIP/103-000000f3 is ringing
– SIP/3200257-000000f4 is ringing
– Local/0113320xxxx@from-internal-0000000c;1 is ringing
localhostCLI>
localhostCLI>
localhostCLI>
localhostCLI>
localhostCLI>
== Spawn extension (macro-dial, s, 7) exited non-zero on ‘SIP/3200257-000000f2’ in macro ‘dial’
== Spawn extension (from-did-direct, 103, 24) exited non-zero on ‘SIP/3200257-000000f2’
– Executing [h@from-did-direct:1] Macro(“SIP/3200257-000000f2”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] ExecIf(“SIP/3200257-000000f2”, “0?Set(CDR(recordingfile)=.wav)”) in new stack
– Executing [s@macro-hangupcall:2] GotoIf(“SIP/3200257-000000f2”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [s@macro-hangupcall:4] Hangup(“SIP/3200257-000000f2”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/3200257-000000f2’ in macro ‘hangupcall’
== Spawn extension (from-did-direct, h, 1) exited non-zero on ‘SIP/3200257-000000f2’
== Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘Local/0113320xxxx@from-internal-0000000c;2’ in macro ‘dialout-trunk’
== Spawn extension (from-internal, 0113320xxxx, 5) exited non-zero on ‘Local/0113320xxxx@from-internal-0000000c;2’
– Executing [h@from-internal:1] Hangup(“Local/0113320xxxx@from-internal-0000000c;2”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'Local/0113320xxxx@from-internal-0000000c;2’
localhostCLI>

harrisp32 · February 27, 2015, 11:25am

Reinigerjm its a very annoying problem!! Have you found anything that might be preventing these calls being diverted??

I’ve tried many different things but none work.

To anyone who might understand the following debug trace, please help us, PLEASE:

[2015-02-26 11:58:34] DEBUG[14729][C-00000014]: res_rtp_asterisk.c:2818 ast_rtp_update_source: Setting the marker bit due to a source update
[2015-02-26 11:58:35] DEBUG[1814]: chan_sip.c:3747 __sip_xmit: Trying to put ‘OPTIONS sip’ onto UDP socket destined for 88.97.X.X:5060
[2015-02-26 11:58:36] DEBUG[1814]: chan_sip.c:9210 find_call: = Looking for Call ID: [email protected] (Checking From) --From tag as633d08c1 --To-tag
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: chan_sip.c:28009 handle_incoming: **** Received CANCEL (14) - Command in SIP CANCEL
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting ‘178.63.X.X’ into…
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: netsock2.c:226 ast_sockaddr_split_hostport: …host ‘178.63.X.X’ and port ‘’.
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: chan_sip.c:3403 sip_alreadygone: Setting SIP_ALREADYGONE on dialog [email protected]
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: res_rtp_asterisk.c:4735 ast_rtp_remote_address_set: Setting RTCP address on RTP instance ‘0xb51c77e4’
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: chan_sip.c:3747 __sip_xmit: Trying to put ‘SIP/2.0 487’ onto UDP socket destined for 178.63.X.X:5060
[2015-02-26 11:58:36] DEBUG[1814][C-00000014]: chan_sip.c:3747 __sip_xmit: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for 178.63.X.X:5060
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:3419 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:3419 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2015-02-26 11:58:36] WARNING[14729][C-00000014]: app_playback.c:493 playback_exec: Playback failed on SIP/441978691XX4-00000017 for silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: app_macro.c:439 _macro_exec: Executed application: Playback
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: app_macro.c:510 _macro_exec: Extension 44789507XXXX, macroexten 200, priority 6 returned normally even though call was hung up
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: app_macro.c:439 _macro_exec: Executed application: Macro
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: pbx.c:6416 __ast_pbx_run: Extension 44789507XXXX, priority 6 returned normally even though call was hung up
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:2550 ast_softhangup_nolock: Soft-Hanging (0x10) up channel ‘SIP/441978691XX4-00000017’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:2550 ast_softhangup_nolock: Soft-Hanging (0x80) up channel ‘SIP/441978691XX4-00000017’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: pbx.c:4921 pbx_extension_helper: Launching ‘Hangup’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:2550 ast_softhangup_nolock: Soft-Hanging (0x20) up channel ‘SIP/441978691XX4-00000017’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: pbx.c:6010 ast_pbx_h_exten_run: Spawn extension (from-internal,h,1) exited non-zero on ‘SIP/441978691XX4-00000017’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: channel.c:2699 ast_hangup: Hanging up channel ‘SIP/441978691XX4-00000017’
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: chan_sip.c:6980 sip_hangup: Hangup call SIP/441978691XX4-00000017, SIP callid [email protected]
[2015-02-26 11:58:36] DEBUG[14729][C-00000014]: res_rtp_asterisk.c:4735 ast_rtp_remote_address_set: Setting RTCP address on RTP instance ‘0xb51c77e4’
[2015-02-26 11:58:36] DEBUG[2205]: manager.c:5682 match_filter: Examining AMI event:
Event: HangupRequest
Privilege: call,all
Channel: SIP/441978691XX4-00000017
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 441978356XXX
CallerIDName: 441978356XXX
ConnectedLineNum:
ConnectedLineName:
Language: en
AccountCode:
Context: from-internal
Exten: 44789507XXXX
Priority: 6
Uniqueid: 1424951909.141

reinigerjm · March 10, 2015, 11:10pm

@harrisp32 , I haven’t found a solution yet, but looking at your call traces, it seems like your problem is distinct from mine. For one, I’m in the US, so our dial plans are naturally going to be different. My system is failing at the macro-dialout-trunk and going to s-INVALIDNMBR, “Dial failed due to trunk reporting Address Incomplete - giving up”

Here’s my call trace for the FreePBX/Asterisk gurus out there. Inbound call from 517XXXXXXX to 312XXXXXXX which rings extension 5440 for 5 seconds before ringallv2 to 5440 and 831XXXXXXX#. Extension 5440 keeps ringing as expected, but no call comes to 831XXXXXXX

http://pastebin.com/x8nQv4uC

reinigerjm · April 2, 2015, 9:54pm

@harrisp32, we found the solution to our problem. We just had to change the “Change External CID Configuration” setting on our Follow-Mes and Ring Groups from “Default” to “Use Dialed Number.” It seems our SIP provider won’t allow us to dial out as a DID that we don’t own (i.e. the caller’s cell phone number).

Hopefully that might help you.

mogan · October 14, 2015, 8:57pm

Hello,

I have the same exact problem. Calling an extension that is in “follow me mode” from internal phones works great, but when that extension is the final destination for an incoming route everything crash and caller just listen to looping ring tone, but nothing ring at all.
I’m pretty sure is something about caller id cause my pstn-provider does not accept that you use a different caller id on his lines.
I tried to change “Change External CID Configuration” settings in follow me module of the followed extension to “Force dialed number” but that didn’t work.

Here is my last log, hope some one can help.
Thanks!
Marco

P.S. I’ve masqueraded my numbers

netsock2.c: == Using SIP RTP TOS bits 184
netsock2.c: == Using SIP RTP CoS mark 5
pbx.c: -- Executing [05XXXX8889@from-trunk:1] Set("SIP/sn4552-0000058f", "__FROM_DID=05XXXX8889") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:2] Gosub("SIP/sn4552-0000058f", "sub-record-check,s,1(in,05XXXX8889,dontcare)") in new stack
pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/sn4552-0000058f", "0?initialized") in new stack
pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/sn4552-0000058f", "__REC_STATUS=INITIALIZED") in new stack
pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/sn4552-0000058f", "NOW=1444854952") in new stack
pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/sn4552-0000058f", "__DAY=14") in new stack
pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/sn4552-0000058f", "__MONTH=10") in new stack
pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/sn4552-0000058f", "__YEAR=2015") in new stack
pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/sn4552-0000058f", "__TIMESTR=20151014-223552") in new stack
pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/sn4552-0000058f", "__FROMEXTEN=unknown") in new stack
pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/sn4552-0000058f", "__MON_FMT=wav") in new stack
pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/sn4552-0000058f", "Recordings initialized") in new stack
pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/sn4552-0000058f", "0?Set(ARG3=dontcare)") in new stack
pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/sn4552-0000058f", "REC_POLICY_MODE_SAVE=") in new stack
pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/sn4552-0000058f", "0?Set(REC_STATUS=NO)") in new stack
pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/sn4552-0000058f", "2?checkaction") in new stack
pbx.c: -- Goto (sub-record-check,s,17)
pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/sn4552-0000058f", "1?sub-record-check,in,1") in new stack
pbx.c: -- Goto (sub-record-check,in,1)
pbx.c: -- Executing [in@sub-record-check:1] NoOp("SIP/sn4552-0000058f", "Inbound Recording Check to 05XXXX8889") in new stack
pbx.c: -- Executing [in@sub-record-check:2] Set("SIP/sn4552-0000058f", "FROMEXTEN=unknown") in new stack
pbx.c: -- Executing [in@sub-record-check:3] ExecIf("SIP/sn4552-0000058f", "9?Set(FROMEXTEN=anonymous)") in new stack
pbx.c: -- Executing [in@sub-record-check:4] Gosub("SIP/sn4552-0000058f", "recordcheck,1(dontcare,in,05XXXX8889)") in new stack
pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/sn4552-0000058f", "Starting recording check against dontcare") in new stack
pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/sn4552-0000058f", "dontcare") in new stack
pbx.c: -- Goto (sub-record-check,recordcheck,3)
pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [in@sub-record-check:5] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:3] Gosub("SIP/sn4552-0000058f", "cidlookup,cidlookup_2,1()") in new stack
pbx.c: -- Executing [cidlookup_2@cidlookup:1] GotoIf("SIP/sn4552-0000058f", "1?cidlookup,cidlookup_return,1") in new stack
pbx.c: -- Goto (cidlookup,cidlookup_return,1)
pbx.c: -- Executing [cidlookup_return@cidlookup:1] ExecIf("SIP/sn4552-0000058f", "0?Set(CALLERID(name)=)") in new stack
pbx.c: -- Executing [cidlookup_return@cidlookup:2] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:4] Gosub("SIP/sn4552-0000058f", "app-blacklist-check,s,1()") in new stack
pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/sn4552-0000058f", "0?blacklisted") in new stack
pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/sn4552-0000058f", "CALLED_BLACKLIST=1") in new stack
pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:5] Set("SIP/sn4552-0000058f", "CDR(did)=05XXXX8889") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:6] ExecIf("SIP/sn4552-0000058f", "1 ?Set(CALLERID(name)=anonymous)") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:7] Set("SIP/sn4552-0000058f", "CHANNEL(musicclass)=prova") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:8] Set("SIP/sn4552-0000058f", "__MOHCLASS=prova") in new stack
[2015-10-14 22:35:52] WARNING[30258][C-0000024b] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
pbx.c: -- Executing [05XXXX8889@from-trunk:9] GotoIf("SIP/sn4552-0000058f", "0?macro-hangupcall") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:10] Set("SIP/sn4552-0000058f", "__CALLINGPRES_SV=allowed_not_screened") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:11] Set("SIP/sn4552-0000058f", "CALLERPRES()=allowed_not_screened") in new stack
pbx.c: -- Executing [05XXXX8889@from-trunk:12] Goto("SIP/sn4552-0000058f", "from-did-direct,2027,1") in new stack
pbx.c: -- Goto (from-did-direct,2027,1)
pbx.c: -- Executing [2027@from-did-direct:1] GotoIf("SIP/sn4552-0000058f", "0?ext-local,2027,1") in new stack
pbx.c: -- Executing [2027@from-did-direct:2] Macro("SIP/sn4552-0000058f", "user-callerid,") in new stack
pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/sn4552-0000058f", "TOUCH_MONITOR=1444854952.2334") in new stack
pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/sn4552-0000058f", "AMPUSER=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/sn4552-0000058f", "0?report") in new stack
pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/sn4552-0000058f", "1?Set(REALCALLERIDNUM=anonymous)") in new stack
pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/sn4552-0000058f", "AMPUSER=") in new stack
pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/sn4552-0000058f", "0?limit") in new stack
pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/sn4552-0000058f", "AMPUSERCIDNAME=") in new stack
pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/sn4552-0000058f", "1?report") in new stack
pbx.c: -- Goto (macro-user-callerid,s,16)
pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/sn4552-0000058f", "0?continue") in new stack
pbx.c: -- Executing [s@macro-user-callerid:17] ExecIf("SIP/sn4552-0000058f", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
pbx.c: -- Executing [s@macro-user-callerid:18] Set("SIP/sn4552-0000058f", "__TTL=64") in new stack
pbx.c: -- Executing [s@macro-user-callerid:19] GotoIf("SIP/sn4552-0000058f", "1?continue") in new stack
pbx.c: -- Goto (macro-user-callerid,s,30)
pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/sn4552-0000058f", "CALLERID(number)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/sn4552-0000058f", "CALLERID(name)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/sn4552-0000058f", "CDR(cnum)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/sn4552-0000058f", "CDR(cnam)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/sn4552-0000058f", "CHANNEL(language)=it") in new stack
pbx.c: -- Executing [2027@from-did-direct:3] Set("SIP/sn4552-0000058f", "DIAL_OPTIONS=TtrI") in new stack
pbx.c: -- Executing [2027@from-did-direct:4] Set("SIP/sn4552-0000058f", "CONNECTEDLINE(num,i)=2027") in new stack
pbx.c: -- Executing [2027@from-did-direct:5] Gosub("SIP/sn4552-0000058f", "sub-presencestate-display,s,1(2027)") in new stack
[2015-10-14 22:35:52] WARNING[30258][C-0000024b] func_presencestate.c: PRESENCE_STATE unknown
pbx.c: -- Executing [s@sub-presencestate-display:1] Goto("SIP/sn4552-0000058f", "state-,1") in new stack
pbx.c: -- Goto (sub-presencestate-display,state-,1)
pbx.c: -- Executing [state-@sub-presencestate-display:1] Set("SIP/sn4552-0000058f", "PRESENCESTATE_DISPLAY=") in new stack
pbx.c: -- Executing [state-@sub-presencestate-display:2] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [2027@from-did-direct:6] Set("SIP/sn4552-0000058f", "CONNECTEDLINE(name)=Snom") in new stack
pbx.c: -- Executing [2027@from-did-direct:7] Set("SIP/sn4552-0000058f", "FM_DIALSTATUS=NOT_INUSE") in new stack
pbx.c: -- Executing [2027@from-did-direct:8] Set("SIP/sn4552-0000058f", "__EXTTOCALL=2027") in new stack
pbx.c: -- Executing [2027@from-did-direct:9] Set("SIP/sn4552-0000058f", "__PICKUPMARK=2027") in new stack
pbx.c: -- Executing [2027@from-did-direct:10] Macro("SIP/sn4552-0000058f", "blkvm-setifempty,") in new stack
pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/sn4552-0000058f", "1?init") in new stack
pbx.c: -- Goto (macro-blkvm-setifempty,s,4)
pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/sn4552-0000058f", "__BLKVM_CHANNEL=SIP/sn4552-0000058f") in new stack
pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/sn4552-0000058f", "SHARED(BLKVM,SIP/sn4552-0000058f)=TRUE") in new stack
pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/sn4552-0000058f", "GOSUB_RETVAL=TRUE") in new stack
pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [2027@from-did-direct:11] GotoIf("SIP/sn4552-0000058f", "1?skipov") in new stack
pbx.c: -- Goto (from-did-direct,2027,14)
pbx.c: -- Executing [2027@from-did-direct:14] Set("SIP/sn4552-0000058f", "RRNODEST=") in new stack
pbx.c: -- Executing [2027@from-did-direct:15] Set("SIP/sn4552-0000058f", "__NODEST=2027") in new stack
pbx.c: -- Executing [2027@from-did-direct:16] GosubIf("SIP/sn4552-0000058f", "1?sub-fmsetcid,s,1()") in new stack
pbx.c: -- Executing [s@sub-fmsetcid:1] Goto("SIP/sn4552-0000058f", "s-forcedid,1") in new stack
pbx.c: -- Goto (sub-fmsetcid,s-forcedid,1)
pbx.c: -- Executing [s-forcedid@sub-fmsetcid:1] ExecIf("SIP/sn4552-0000058f", "1?Set(__TRUNKCIDOVERRIDE=05XXXX8889)") in new stack
pbx.c: -- Executing [s-forcedid@sub-fmsetcid:2] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [2027@from-did-direct:17] Set("SIP/sn4552-0000058f", "RecordMethod=Group") in new stack
pbx.c: -- Executing [2027@from-did-direct:18] Gosub("SIP/sn4552-0000058f", "sub-record-check,s,1(exten,2027,)") in new stack
pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/sn4552-0000058f", "9?initialized") in new stack
pbx.c: -- Goto (sub-record-check,s,10)
pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/sn4552-0000058f", "Recordings initialized") in new stack
pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/sn4552-0000058f", "1?Set(ARG3=dontcare)") in new stack
pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/sn4552-0000058f", "REC_POLICY_MODE_SAVE=") in new stack
pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/sn4552-0000058f", "0?Set(REC_STATUS=NO)") in new stack
pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/sn4552-0000058f", "5?checkaction") in new stack
pbx.c: -- Goto (sub-record-check,s,17)
pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/sn4552-0000058f", "1?sub-record-check,exten,1") in new stack
pbx.c: -- Goto (sub-record-check,exten,1)
pbx.c: -- Executing [exten@sub-record-check:1] NoOp("SIP/sn4552-0000058f", "Exten Recording Check between anonymous and 2027") in new stack
pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/sn4552-0000058f", "CALLTYPE=external") in new stack
pbx.c: -- Executing [exten@sub-record-check:3] ExecIf("SIP/sn4552-0000058f", "0?Set(CALLTYPE=)") in new stack
pbx.c: -- Executing [exten@sub-record-check:4] Set("SIP/sn4552-0000058f", "CALLEE=dontcare") in new stack
pbx.c: -- Executing [exten@sub-record-check:5] ExecIf("SIP/sn4552-0000058f", "0?Set(CALLEE=dontcare)") in new stack
pbx.c: -- Executing [exten@sub-record-check:6] GotoIf("SIP/sn4552-0000058f", "1?callee") in new stack
pbx.c: -- Goto (sub-record-check,exten,11)
pbx.c: -- Executing [exten@sub-record-check:11] Gosub("SIP/sn4552-0000058f", "recordcheck,1(dontcare,external,2027)") in new stack
pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/sn4552-0000058f", "Starting recording check against dontcare") in new stack
pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/sn4552-0000058f", "dontcare") in new stack
pbx.c: -- Goto (sub-record-check,recordcheck,3)
pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [2027@from-did-direct:19] GotoIf("SIP/sn4552-0000058f", "1 ?skipsimple") in new stack
pbx.c: -- Goto (from-did-direct,2027,21)
pbx.c: -- Executing [2027@from-did-direct:21] Set("SIP/sn4552-0000058f", "RingGroupMethod=ringall") in new stack
pbx.c: -- Executing [2027@from-did-direct:22] Set("SIP/sn4552-0000058f", "_FMGRP=2027") in new stack
pbx.c: -- Executing [2027@from-did-direct:23] GotoIf("SIP/sn4552-0000058f", "0?doconfirm") in new stack
pbx.c: -- Executing [2027@from-did-direct:24] Macro("SIP/sn4552-0000058f", "dial,40,m(prova),349XXXX039#") in new stack
pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/sn4552-0000058f", "0?dial") in new stack
pbx.c: -- Executing [s@macro-dial:2] Set("SIP/sn4552-0000058f", "CHANNEL(musicclass)=prova") in new stack
pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/sn4552-0000058f", "dialparties.agi") in new stack
res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
res_agi.c: dialparties.agi: Starting New Dialparties.agi
res_agi.c: dialparties.agi: Caller ID name is 'anonymous' number is 'anonymous'
res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
res_agi.c: -- dialparties.agi: Added extension 349XXXX039# to extension map
res_agi.c: -- dialparties.agi: Extension 349XXXX039# cf is disabled
res_agi.c: -- dialparties.agi: Filtered ARG3: 349XXXX039
res_agi.c: -- <SIP/sn4552-0000058f>AGI Script dialparties.agi completed, returning 0
pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/sn4552-0000058f", "Local/349XXXX039@from-internal/n,40,m(prova)M(auto-blkvm)") in new stack
app_dial.c: -- Called Local/349XXXX039@from-internal/n
res_musiconhold.c: -- Started music on hold, class 'prova', on SIP/sn4552-0000058f
pbx.c: -- Executing [349XXXX039@from-internal:1] Macro("Local/349XXXX039@from-internal-000001b7;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/349XXXX039@from-internal-000001b7;2", "TOUCH_MONITOR=1444854953.2336") in new stack
pbx.c: -- Executing [s@macro-user-callerid:2] Set("Local/349XXXX039@from-internal-000001b7;2", "AMPUSER=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("Local/349XXXX039@from-internal-000001b7;2", "0?report") in new stack
pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("Local/349XXXX039@from-internal-000001b7;2", "1?Set(REALCALLERIDNUM=anonymous)") in new stack
pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/349XXXX039@from-internal-000001b7;2", "AMPUSER=") in new stack
pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/349XXXX039@from-internal-000001b7;2", "0?limit") in new stack
pbx.c: -- Executing [s@macro-user-callerid:7] Set("Local/349XXXX039@from-internal-000001b7;2", "AMPUSERCIDNAME=") in new stack
pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("Local/349XXXX039@from-internal-000001b7;2", "1?report") in new stack
pbx.c: -- Goto (macro-user-callerid,s,16)
pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("Local/349XXXX039@from-internal-000001b7;2", "1?continue") in new stack
pbx.c: -- Goto (macro-user-callerid,s,30)
pbx.c: -- Executing [s@macro-user-callerid:30] Set("Local/349XXXX039@from-internal-000001b7;2", "CALLERID(number)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:31] Set("Local/349XXXX039@from-internal-000001b7;2", "CALLERID(name)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:32] Set("Local/349XXXX039@from-internal-000001b7;2", "CDR(cnum)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:33] Set("Local/349XXXX039@from-internal-000001b7;2", "CDR(cnam)=anonymous") in new stack
pbx.c: -- Executing [s@macro-user-callerid:34] Set("Local/349XXXX039@from-internal-000001b7;2", "CHANNEL(language)=it") in new stack
pbx.c: -- Executing [349XXXX039@from-internal:2] NoCDR("Local/349XXXX039@from-internal-000001b7;2", "") in new stack
pbx.c: -- Executing [349XXXX039@from-internal:3] Progress("Local/349XXXX039@from-internal-000001b7;2", "") in new stack
app_dial.c: -- Local/349XXXX039@from-internal-000001b7;1 is making progress passing it to SIP/sn4552-0000058f
pbx.c: -- Executing [349XXXX039@from-internal:4] Wait("Local/349XXXX039@from-internal-000001b7;2", "1") in new stack
pbx.c: -- Executing [349XXXX039@from-internal:5] Progress("Local/349XXXX039@from-internal-000001b7;2", "") in new stack
pbx.c: -- Executing [349XXXX039@from-internal:6] Playback("Local/349XXXX039@from-internal-000001b7;2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
app_dial.c: -- Local/349XXXX039@from-internal-000001b7;1 is making progress passing it to SIP/sn4552-0000058f
file.c: -- <Local/349XXXX039@from-internal-000001b7;2> Playing 'silence/1.alaw' (language 'it')
file.c: -- <Local/349XXXX039@from-internal-000001b7;2> Playing 'cannot-complete-as-dialed.alaw' (language 'it')
file.c: -- <Local/349XXXX039@from-internal-000001b7;2> Playing 'check-number-dial-again.alaw' (language 'it')
pbx.c: -- Executing [349XXXX039@from-internal:7] Wait("Local/349XXXX039@from-internal-000001b7;2", "1") in new stack
pbx.c: -- Executing [349XXXX039@from-internal:8] Congestion("Local/349XXXX039@from-internal-000001b7;2", "20") in new stack
app_dial.c: -- Local/349XXXX039@from-internal-000001b7;1 is circuit-busy
pbx.c: == Spawn extension (from-internal, 349XXXX039, 8) exited non-zero on 'Local/349XXXX039@from-internal-000001b7;2'
pbx.c: -- Executing [h@from-internal:1] Hangup("Local/349XXXX039@from-internal-000001b7;2", "") in new stack
pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/349XXXX039@from-internal-000001b7;2'
app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
res_musiconhold.c: -- Stopped music on hold on SIP/sn4552-0000058f
pbx.c: -- Executing [s@macro-dial:8] Set("SIP/sn4552-0000058f", "DIALSTATUS=CONGESTION") in new stack
pbx.c: -- Executing [s@macro-dial:9] GosubIf("SIP/sn4552-0000058f", "0?CONGESTION,1") in new stack
pbx.c: -- Executing [2027@from-did-direct:25] Goto("SIP/sn4552-0000058f", "nextstep") in new stack
pbx.c: -- Goto (from-did-direct,2027,27)
pbx.c: -- Executing [2027@from-did-direct:27] Set("SIP/sn4552-0000058f", "RingGroupMethod=") in new stack
pbx.c: -- Executing [2027@from-did-direct:28] GotoIf("SIP/sn4552-0000058f", "0?nodest") in new stack
pbx.c: -- Executing [2027@from-did-direct:29] Set("SIP/sn4552-0000058f", "__NODEST=") in new stack
pbx.c: -- Executing [2027@from-did-direct:30] Set("SIP/sn4552-0000058f", "__PICKUPMARK=") in new stack
pbx.c: -- Executing [2027@from-did-direct:31] Macro("SIP/sn4552-0000058f", "blkvm-clr,") in new stack
pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("SIP/sn4552-0000058f", "SHARED(BLKVM,SIP/sn4552-0000058f)=") in new stack
pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("SIP/sn4552-0000058f", "GOSUB_RETVAL=") in new stack
pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/sn4552-0000058f", "") in new stack
pbx.c: -- Executing [2027@from-did-direct:32] Set("SIP/sn4552-0000058f", "DIALSTATUS=NOANSWER") in new stack
pbx.c: -- Executing [2027@from-did-direct:33] Goto("SIP/sn4552-0000058f", "ext-local,2027,dest") in new stack
pbx.c: -- Goto (ext-local,2027,3)
pbx.c: -- Executing [2027@ext-local:3] Set("SIP/sn4552-0000058f", "__PICKUPMARK=") in new stack
pbx.c: -- Executing [2027@ext-local:4] Goto("SIP/sn4552-0000058f", ",return,1") in new stack
pbx.c: -- Goto (ext-local,return,1)
WARNING[30258][C-0000024b] pbx.c: Channel 'SIP/sn4552-0000058f' sent to invalid extension but no invalid handler: context,exten,priority=ext-local,return,1
pbx.c: -- Executing [h@ext-local:1] Macro("SIP/sn4552-0000058f", "hangupcall,") in new stack
pbx.c: -- Executing [s@macro-hangupcall:1] ExecIf("SIP/sn4552-0000058f", "0?Set(CDR(recordingfile)=.wav)") in new stack
pbx.c: -- Executing [s@macro-hangupcall:2] GotoIf("SIP/sn4552-0000058f", "1?theend") in new stack
pbx.c: -- Goto (macro-hangupcall,s,4)
pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/sn4552-0000058f", "") in new stack
app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/sn4552-0000058f' in macro 'hangupcall'
pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/sn4552-0000058f'

mogan · October 14, 2015, 9:24pm

I think I found something interesting.
Looking at the logs, the one where call is correctly routed to my mobile and the other, I found this differences:

this is from the “good one” log

Executing [s@macro-dial:7] Dial(“SIP/2028-00000591”, “Local/349XXXX039@from-internal/n,40,m(prova)M(auto-blkvm)”) in new stack

As you can see when I call from internal 2028 to the internal 2027 (that is configured to “follow me” to 349XXXX039#) the system execute dial function passing it the extension number 2028 for identifier.

this is from the “bad one” log

Executing [s@macro-dial:7] Dial(“SIP/sn4552-0000058f”, “Local/349XXXX039@from-internal/n,40,m(prova)M(auto-blkvm)”) in new stack

Here the identifier is “sn4552”, my ISDN trunk name. It’s inbound route point to the 2027 extension that is configure to follow all cals to 349XXXX039#.

Hope this help.
Thanks
Marco

harrisp32 · January 1, 2016, 7:23pm

Its a long time coming but I would like to thank everyone for their help with this problem.

It was a few days ago when I revisited this problem and reading a few other potential solutions to this issue when I thought I’d try Ring Groups with Misc Destination instead of Follow Me, A bit of messing around and finally it worked. I also tried setting up DISA and got that to work too.

But there’s a problem, on Inbound Routes I change Set Destination to Ring Groups and my ring group, that works as expected, Now if I then set it to DISA, that works, but here is the problem, if I then set it back to Ring Groups, then Ring Groups does no longer work even though the setting are the same and my other dial patterns don’t work with my other DID numbers either, this seems to break things for other inbound/outbound calls and I don’t know why.

Does anyone know anything about this???