Outbound calls go back to IVR!

Hello all,
The topology is as follows. Freepbx is using Grandstream GXX4104 with two PSTN lines. I have set up an IVR.
All incoming calls are working fine, no problem with hangups or CallerID or disconnect tones.

The weird part is when you try to call from an extension (any extension) to an external phone (gsm or landline). The result is to hear the IVR options!! I tried to disable one by one and eventually both trunks (both PSTN) and the result is the same. Even with no trunks enabled at all you hear the IVR when you try to make an outbound call. Could you please help me out?

Please find below the logs…

[2021-09-21 12:35:42] VERBOSE[2562] netsock2.c: Using SIP RTP Audio TOS bits 184
[2021-09-21 12:35:42] VERBOSE[2562] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2021-09-21 12:35:42] VERBOSE[2562] netsock2.c: Using SIP RTP Audio CoS mark 5
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [06938751318@from-trunk:1] NoOp("PJSIP/23-0000000e", "Catch-All DID Match - Found 06938751318 - You probably want a DID for this.") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [06938751318@from-trunk:2] Set("PJSIP/23-0000000e", "__FROM_DID=06938751318") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [06938751318@from-trunk:3] Goto("PJSIP/23-0000000e", "ext-did,s,1") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (ext-did,s,1)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:1] Set("PJSIP/23-0000000e", "__DIRECTION=INBOUND") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:2] Gosub("PJSIP/23-0000000e", "sub-record-check,s,1(in,s,dontcare)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/23-0000000e", "0?initialized") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/23-0000000e", "__REC_STATUS=INITIALIZED") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/23-0000000e", "NOW=1632216942") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/23-0000000e", "__DAY=21") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/23-0000000e", "__MONTH=09") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/23-0000000e", "__YEAR=2021") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/23-0000000e", "__TIMESTR=20210921-123542") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/23-0000000e", "__FROMEXTEN=unknown") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/23-0000000e", "__MON_FMT=wav") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/23-0000000e", "Recordings initialized") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/23-0000000e", "0?Set(ARG3=dontcare)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/23-0000000e", "REC_POLICY_MODE_SAVE=") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/23-0000000e", "0?Set(REC_STATUS=NO)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/23-0000000e", "2?checkaction") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (sub-record-check,s,17)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/23-0000000e", "1?sub-record-check,in,1") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (sub-record-check,in,1)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/23-0000000e", "Inbound Recording Check to s") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/23-0000000e", "FROMEXTEN=unknown") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/23-0000000e", "2?Set(FROMEXTEN=23)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/23-0000000e", "recordcheck,1(dontcare,in,s)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/23-0000000e", "Starting recording check against dontcare") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/23-0000000e", "dontcare") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:3] Set("PJSIP/23-0000000e", "CHANNEL(tonezone)=gr") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:4] ExecIf("PJSIP/23-0000000e", "0?Set(__FROM_DID=s)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:5] Set("PJSIP/23-0000000e", "returnhere=1") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:6] Gosub("PJSIP/23-0000000e", "app-blacklist-check,s,1()") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/23-0000000e", "0?blacklisted") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/23-0000000e", "CALLED_BLACKLIST=1") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:7] Set("PJSIP/23-0000000e", "CDR(did)=06938751318") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:8] GotoIf("PJSIP/23-0000000e", "0?") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:9] ExecIf("PJSIP/23-0000000e", "0 ?Set(CALLERID(name)=23)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:10] Set("PJSIP/23-0000000e", "__MOHCLASS=") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:11] Set("PJSIP/23-0000000e", "__REVERSAL_REJECT=FALSE") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:12] GotoIf("PJSIP/23-0000000e", "1?post-reverse-charge") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (ext-did,s,14)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:14] NoOp("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:15] Set("PJSIP/23-0000000e", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:16] Set("PJSIP/23-0000000e", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:17] Set("PJSIP/23-0000000e", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:18] Set("PJSIP/23-0000000e", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:19] NoOp("PJSIP/23-0000000e", "CallerID Entry Point") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:20] Set("PJSIP/23-0000000e", "__CRM_DIRECTION=INBOUND") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:21] Set("PJSIP/23-0000000e", "__CRM_SOURCE=23") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:22] Set("PJSIP/23-0000000e", "__CRM_LINKEDID=1632216942.17") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:23] AGI("PJSIP/23-0000000e", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] res_agi.c: <PJSIP/23-0000000e>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:24] ExecIf("PJSIP/23-0000000e", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ext-did:25] Goto("PJSIP/23-0000000e", "timeconditions,1,1") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (timeconditions,1,1)
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:1] Set("PJSIP/23-0000000e", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:2] Set("PJSIP/23-0000000e", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:3] NoOp("PJSIP/23-0000000e", "TIMENOW: 12:35,Tue,21,Sep,Europe/Athens") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:4] NoOp("PJSIP/23-0000000e", "TIMEMATCHED: TRUE") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:5] GotoIfTime("PJSIP/23-0000000e", "08:00-16:00,mon-sat,1-31,jan-dec,Europe/Athens?truestate") in new stack
[2021-09-21 12:35:42] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (timeconditions,1,14)
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:14] GotoIf("PJSIP/23-0000000e", "0?falsegoto") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:15] ExecIf("PJSIP/23-0000000e", "0?Set(DB(TC/1)=)") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:16] Set("PJSIP/23-0000000e", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:17] ExecIf("PJSIP/23-0000000e", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [1@timeconditions:18] GotoIf("PJSIP/23-0000000e", "1?ivr-2,s,1") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx_builtins.c: Goto (ivr-2,s,1)
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:1] Set("PJSIP/23-0000000e", "TIMEOUT_LOOPCOUNT=0") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:2] Set("PJSIP/23-0000000e", "INVALID_LOOPCOUNT=0") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:3] Set("PJSIP/23-0000000e", "_IVR_CONTEXT_ivr-2=") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:4] Set("PJSIP/23-0000000e", "_IVR_CONTEXT=ivr-2") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:5] Set("PJSIP/23-0000000e", "__IVR_RETVM=") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:6] GotoIf("PJSIP/23-0000000e", "0?skip") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:7] Answer("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:8] Set("PJSIP/23-0000000e", "IVR_MSG=custom/Anakiklosi-day") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:9] Set("PJSIP/23-0000000e", "TIMEOUT(digit)=0") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] func_timeout.c: Digit timeout set to 0.000
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@ivr-2:10] Read("PJSIP/23-0000000e", "IVREXT,custom/Anakiklosi-day,,,0,0") in new stack
[2021-09-21 12:35:43] VERBOSE[7304][C-0000000b] file.c: <PJSIP/23-0000000e> Playing 'custom/Anakiklosi-day.ulaw' (language 'en')
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] app_read.c: User disconnected
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [h@ivr-2:1] Hangup("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Spawn extension (ivr-2, h, 1) exited non-zero on 'PJSIP/23-0000000e'
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] app_stack.c: PJSIP/23-0000000e Internal Gosub(crm-hangup,s,1) start
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/23-0000000e", "Sending Hangup to CRM") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/23-0000000e", "HANGUP CAUSE: 16") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/23-0000000e", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/23-0000000e", "MASTER CHANNEL: 1632216942.17 = 1632216942.17") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/23-0000000e", "0?return") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/23-0000000e", "__CRM_HANGUP=1") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/23-0000000e", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] res_agi.c: <PJSIP/23-0000000e>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/23-0000000e", "") in new stack
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] app_stack.c: Spawn extension (ivr-2, h, 1) exited non-zero on 'PJSIP/23-0000000e'
[2021-09-21 12:35:44] VERBOSE[7304][C-0000000b] app_stack.c: PJSIP/23-0000000e Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2021-09-21 12:35:49] WARNING[2562] res_pjsip_registrar.c: Endpoint 'anonymous' (192.168.1.252:5060) has no configured AORs
[2021-09-21 12:35:52] WARNING[2562] res_pjsip_registrar.c: Endpoint 'anonymous' (192.168.1.252:5062) has no configured AORs
[2021-09-21 12:36:00] NOTICE[2562] res_pjsip_exten_state.c: Endpoint '23' state subscription failed: Extension '204' does not exist in context 'from-trunk' or has no associated hint
[2021-09-21 12:36:01] NOTICE[2562] res_pjsip_exten_state.c: Endpoint '23' state subscription failed: Extension '204' does not exist in context 'from-trunk' or has no associated hint

You say this is an outbound call from a local extension, but the log lines are for a call in the from-trunk context. If this is a local extension it needs to be in the from-internal context.

Igaetz, thank you for your immediate response. I am puzzled because the whole thing was set up in my office and worked smoothly without any problems whatsoever and when I moved everything to the client’s office and made the appropriate changes, of course, it went sour with the outgoing calls…

I will try “from-internal” and come back.

Igaetz,i changed from-internal among afewother things. Now when i try to call a number i hear the message" The number you have dialed in not in service…" Can you please help me out?

indent preformatted text by 4 spaces

[2021-09-24 14:39:25] VERBOSE[21470] netsock2.c: Using SIP RTP Audio TOS bits 184
[2021-09-24 14:39:25] VERBOSE[21470] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2021-09-24 14:39:25] VERBOSE[21470] netsock2.c: Using SIP RTP Audio CoS mark 5
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:1] Macro(“PJSIP/20-00000020”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/20-00000020”, “TOUCH_MONITOR=1632483565.42”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/20-00000020”, “CHANCONTEXT=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] Set(“PJSIP/20-00000020”, “CHANCONTEXT=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] Set(“PJSIP/20-00000020”, “CHANEXTENCONTEXT=20-00000020”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/20-00000020”, “CHANEXTEN=20-00000020”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] Set(“PJSIP/20-00000020”, “CALLERID(number)=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/20-00000020”, “AMPUSER=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] Set(“PJSIP/20-00000020”, “HOTDESCKCHAN=20-00000020”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] Set(“PJSIP/20-00000020”, “HOTDESKEXTEN=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/20-00000020”, “HOTDESKCALL=0”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] ExecIf(“PJSIP/20-00000020”, “0?Set(HOTDESKCALL=1)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(name)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/20-00000020”, “0?report”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/20-00000020”, “1?Set(REALCALLERIDNUM=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:15] Set(“PJSIP/20-00000020”, “AMPUSER=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/20-00000020”, “0?limit”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:17] Set(“PJSIP/20-00000020”, “AMPUSERCIDNAME=Console”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:18] ExecIf(“PJSIP/20-00000020”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/20-00000020”, “0?report”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/20-00000020”, “AMPUSERCID=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:21] Set(“PJSIP/20-00000020”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:22] Set(“PJSIP/20-00000020”, “CALLERID(all)=“Console” <20>”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:23] ExecIf(“PJSIP/20-00000020”, “0?Set(CUSDIAL=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:24] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(all)=“Console” <20>)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“PJSIP/20-00000020”, “0?limit”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:26] ExecIf(“PJSIP/20-00000020”, “1?Set(GROUP(concurrency_limit)=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:27] ExecIf(“PJSIP/20-00000020”, “0?Set(CHANNEL(language)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:28] NoOp(“PJSIP/20-00000020”, “Macro Depth is 1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:29] GotoIf(“PJSIP/20-00000020”, “1?report2:macroerror”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(“PJSIP/20-00000020”, “1?continue”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:49] Set(“PJSIP/20-00000020”, “CALLERID(number)=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:50] Set(“PJSIP/20-00000020”, “CALLERID(name)=Console”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:51] GotoIf(“PJSIP/20-00000020”, “0?cnum”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:52] Set(“PJSIP/20-00000020”, “CDR(cnam)=Console”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:53] Set(“PJSIP/20-00000020”, “CDR(cnum)=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-user-callerid:54] Set(“PJSIP/20-00000020”, “CHANNEL(language)=en”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:2] Gosub(“PJSIP/20-00000020”, “sub-record-check,s,1(out,96938751318,dontcare)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/20-00000020”, “0?initialized”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/20-00000020”, “__REC_STATUS=INITIALIZED”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/20-00000020”, “NOW=1632483565”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/20-00000020”, “__DAY=24”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/20-00000020”, “__MONTH=09”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/20-00000020”, “__YEAR=2021”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/20-00000020”, “__TIMESTR=20210924-143925”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/20-00000020”, “__FROMEXTEN=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/20-00000020”, “__MON_FMT=wav”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/20-00000020”, “Recordings initialized”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/20-00000020”, “0?Set(ARG3=dontcare)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/20-00000020”, “REC_POLICY_MODE_SAVE=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/20-00000020”, “0?Set(REC_STATUS=NO)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/20-00000020”, “3?checkaction”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/20-00000020”, “1?sub-record-check,out,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,1)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/20-00000020”, “Outbound Recording Check from 20 to 96938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/20-00000020”, “RECMODE=dontcare”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/20-00000020”, “1?Goto(routewins)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,7)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/20-00000020”, “recordcheck,1(dontcare,out,96938751318)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/20-00000020”, “Starting recording check against dontcare”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/20-00000020”, “dontcare”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/20-00000020”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/20-00000020”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:3] ExecIf(“PJSIP/20-00000020”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:4] Set(“PJSIP/20-00000020”, “_ROUTEID=2”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:5] Set(“PJSIP/20-00000020”, “_ROUTENAME=Outbound_Route”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:6] Set(“PJSIP/20-00000020”, “MOHCLASS=default”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:7] ExecIf(“PJSIP/20-00000020”, “1?Set(TRUNKCIDOVERRIDE=2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:8] Set(“PJSIP/20-00000020”, “_CALLERIDNAMEINTERNAL=Console”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:9] Set(“PJSIP/20-00000020”, “_CALLERIDNUMINTERNAL=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:10] Set(“PJSIP/20-00000020”, “_EMAILNOTIFICATION=FALSE”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:11] Set(“PJSIP/20-00000020”, “_NODEST=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [96938751318@from-internal:12] Macro(“PJSIP/20-00000020”, “dialout-trunk,2,6938751318,off”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/20-00000020”, “DIAL_TRUNK=2”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/20-00000020”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“PJSIP/20-00000020”, “0?sub-pincheck,s,1()”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(num)=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“PJSIP/20-00000020”, “0?disabletrunk,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/20-00000020”, “DIAL_NUMBER=6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/20-00000020”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/20-00000020”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/20-00000020”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“PJSIP/20-00000020”, “0?nomax”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“PJSIP/20-00000020”, “0?chanfull”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“PJSIP/20-00000020”, “0?skipoutcid”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“PJSIP/20-00000020”, “outbound-callerid,2”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/20-00000020”, “20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/20-00000020”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/20-00000020”, “off”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:6] Set(“PJSIP/20-00000020”, “HOTDESCKCHAN=20-00000020”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“PJSIP/20-00000020”, “HOTDESKEXTEN=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“PJSIP/20-00000020”, “HOTDESKCALL=0”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf(“PJSIP/20-00000020”, “0?Set(HOTDESKCALL=1)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(name)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:11] Set(“PJSIP/20-00000020”, “ALLOWTHISROUTE=NO”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf(“PJSIP/20-00000020”, “0?Set(ALLOWTHISROUTE=YES)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf(“PJSIP/20-00000020”, “0?Hangup()”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf(“PJSIP/20-00000020”, “0?Set(REALCALLERIDNUM=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“PJSIP/20-00000020”, “0?Set(AMPUSER=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf(“PJSIP/20-00000020”, “1?normcid”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“PJSIP/20-00000020”, “USEROUTCID=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“PJSIP/20-00000020”, “EMERGENCYCID=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/20-00000020”, “0?Set(EMERGENCYCID=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:23] Set(“PJSIP/20-00000020”, “TRUNKOUTCID=2641056719”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf(“PJSIP/20-00000020”, “1?trunkcid”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf(“PJSIP/20-00000020”, “1?Set(CALLERID(all)=2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(all)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf(“PJSIP/20-00000020”, “1?Set(CALLERID(all)=2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(all)=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERID(all)=20)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:35] Set(“PJSIP/20-00000020”, “TIOHIDE=no”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf(“PJSIP/20-00000020”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:40] Set(“PJSIP/20-00000020”, “CDR(outbound_cnum)=2641056719”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:41] Set(“PJSIP/20-00000020”, “CDR(outbound_cnam)=”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“PJSIP/20-00000020”, “0?sub-flp-2,s,1()”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/20-00000020”, “OUTNUM=6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“PJSIP/20-00000020”, “custom=SIP/FXO”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/20-00000020”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“PJSIP/20-00000020”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“PJSIP/20-00000020”, “dialout-trunk-predial-hook,”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/20-00000020”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“PJSIP/20-00000020”, “0?skipcrm”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“PJSIP/20-00000020”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“PJSIP/20-00000020”, “__CRM_DESTINATION=6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:23] Set(“PJSIP/20-00000020”, “__CRM_SOURCE=20”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:24] AGI(“PJSIP/20-00000020”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] res_agi.c: <PJSIP/20-00000020>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“PJSIP/20-00000020”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp(“PJSIP/20-00000020”, “CRM Finished”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf(“PJSIP/20-00000020”, “0?bypass,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“PJSIP/20-00000020”, “1?Set(CONNECTEDLINE(num,i)=6938751318)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“PJSIP/20-00000020”, “1?Set(CONNECTEDLINE(name,i)=CID:2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf(“PJSIP/20-00000020”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“PJSIP/20-00000020”, “0?customtrunk”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf(“PJSIP/20-00000020”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:33] Set(“PJSIP/20-00000020”, “HASH(__SIPHEADERS,Alert-Info)=unset”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:34] Dial(“PJSIP/20-00000020”, “SIP/FXO/6938751318,300,Tb(func-apply-sipheaders^s^1,(2))U(sub-send-obroute-email^6938751318^96938751318^2^1632483565^^2641056719)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: SIP/FXO-0000000a Internal Gosub(func-apply-sipheaders,s,1(2)) start
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/FXO-0000000a”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/FXO-0000000a”, “Applying SIP Headers to channel SIP/FXO-0000000a”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/FXO-0000000a”, “TECH=SIP”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“SIP/FXO-0000000a”, “SIPHEADERKEYS=Alert-Info”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/FXO-0000000a”, “1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“SIP/FXO-0000000a”, “sipheader=unset”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“SIP/FXO-0000000a”, “1?SIPRemoveHeader(Alert-Info:)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/FXO-0000000a”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“SIP/FXO-0000000a”, “0?Set(sipheader=http://127.0.0.1;info=unset)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“SIP/FXO-0000000a”, “0?Set(sipheader=http://127.0.0.1unset)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“SIP/FXO-0000000a”, “0?SIPAddHeader(Alert-Info:unset)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“SIP/FXO-0000000a”, “0?Set(PJSIP_HEADER(add,Alert-Info)=unset)”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile(“SIP/FXO-0000000a”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While(“SIP/FXO-0000000a”, “0”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“SIP/FXO-0000000a”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: Spawn extension (from-trunk, 96938751318, 1) exited non-zero on ‘SIP/FXO-0000000a’
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: SIP/FXO-0000000a Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_dial.c: Called SIP/FXO/6938751318
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-sip-external:1] NoOp(“PJSIP/anonymous-00000021”, “Received incoming SIP connection from unknown peer to 6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-sip-external:2] Set(“PJSIP/anonymous-00000021”, “DID=6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-sip-external:3] Goto(“PJSIP/anonymous-00000021”, “s,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx_builtins.c: Goto (from-sip-external,s,1)
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-sip-external:1] GotoIf(“PJSIP/anonymous-00000021”, “1?setlanguage:checkanon”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx_builtins.c: Goto (from-sip-external,s,2)
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-sip-external:2] Set(“PJSIP/anonymous-00000021”, “CHANNEL(language)=en”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-sip-external:3] GotoIf(“PJSIP/anonymous-00000021”, “0?noanonymous”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-sip-external:4] Goto(“PJSIP/anonymous-00000021”, “from-trunk,6938751318,1”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx_builtins.c: Goto (from-trunk,6938751318,1)
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-trunk:1] Set(“PJSIP/anonymous-00000021”, “__FROM_DID=6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-trunk:2] NoOp(“PJSIP/anonymous-00000021”, “Received an unknown call with DID set to 6938751318”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [6938751318@from-trunk:3] Goto(“PJSIP/anonymous-00000021”, “s,a2”) in new stack
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx_builtins.c: Goto (from-trunk,s,2)
[2021-09-24 14:39:25] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-trunk:2] Answer(“PJSIP/anonymous-00000021”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_dial.c: SIP/FXO-0000000a answered PJSIP/20-00000020
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: SIP/FXO-0000000a Internal Gosub(sub-send-obroute-email,s,1(6938751318,96938751318,2,1632483565,2641056719)) start
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf(“SIP/FXO-0000000a”, “0?sendEmail”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp(“SIP/FXO-0000000a”, “email notifications disabled…exiting.”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@sub-send-obroute-email:3] Return(“SIP/FXO-0000000a”, “”) in new stack
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: Spawn extension (from-trunk, , 1) exited non-zero on ‘SIP/FXO-0000000a’
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] app_stack.c: SIP/FXO-0000000a Internal Gosub(sub-send-obroute-email,s,1(6938751318,96938751318,2,1632483565,2641056719)) complete GOSUB_RETVAL=
[2021-09-24 14:39:25] VERBOSE[23591][C-0000001f] bridge_channel.c: Channel SIP/FXO-0000000a joined ‘simple_bridge’ basic-bridge <6308b51c-ed3e-411a-9804-d76d7f688890>
[2021-09-24 14:39:25] VERBOSE[23578][C-0000001f] bridge_channel.c: Channel PJSIP/20-00000020 joined ‘simple_bridge’ basic-bridge <6308b51c-ed3e-411a-9804-d76d7f688890>
[2021-09-24 14:39:26] WARNING[23590][C-00000020] chan_sip.c: This function can only be used on SIP channels.
[2021-09-24 14:39:26] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-trunk:3] Log(“PJSIP/anonymous-00000021”, "WARNING,Friendly Scanner from ") in new stack
[2021-09-24 14:39:26] WARNING[23590][C-00000020] Ext. s: Friendly Scanner from
[2021-09-24 14:39:26] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-trunk:4] Wait(“PJSIP/anonymous-00000021”, “2”) in new stack
[2021-09-24 14:39:28] VERBOSE[23590][C-00000020] pbx.c: Executing [s@from-trunk:5] Playback(“PJSIP/anonymous-00000021”, “ss-noservice”) in new stack
[2021-09-24 14:39:28] VERBOSE[23590][C-00000020] file.c: <PJSIP/anonymous-00000021> Playing ‘ss-noservice.ulaw’ (language ‘en’)
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] bridge_channel.c: Channel PJSIP/20-00000020 left ‘simple_bridge’ basic-bridge <6308b51c-ed3e-411a-9804-d76d7f688890>
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] app_macro.c: Spawn extension (macro-dialout-trunk, s, 34) exited non-zero on ‘PJSIP/20-00000020’ in macro ‘dialout-trunk’
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] pbx.c: Spawn extension (from-internal, 96938751318, 12) exited non-zero on ‘PJSIP/20-00000020’
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/20-00000020”, “hangupcall”) in new stack
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/20-00000020”, “1?theend”) in new stack
[2021-09-24 14:39:29] VERBOSE[23578][C-0000001f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2021-09-24 14:39:29] VERBOSE[23591][C-0000001f] bridge_channel.c: Channel SIP/FXO-0000000a left ‘simple_bridge’ basic-bridge <6308b51c-ed3e-411a-9804-d76d7f688890>

from your logs, it looks like you tried to call:
6938751318

that number goes fast-busy, and may indeed ACTUALLY be not-in-service…

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.