Closed HLeithner closed 1 week ago
Hi @HLeithner , Can you share the asterisk cli logs when you tried to do direct pickup which is not working.
HI @msanthosh18
sure, I replaced some numbers and names from the log
Call flow
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-toheader-did:1] NoOp("PJSIP/TrunkProvider-00000574", "Attempting to extract DID from SIP To header") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-toheader-did:2] GotoIf("PJSIP/TrunkProvider-00000574", "1?PJSIP") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (from-pstn-toheader-did,434444444440,5)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-toheader-did:5] Goto("PJSIP/TrunkProvider-00000574", "from-pstn-did,434444444440,1") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (from-pstn-did,434444444440,1)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:1] Set("PJSIP/TrunkProvider-00000574", "__DIRECTION=INBOUND") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:2] Gosub("PJSIP/TrunkProvider-00000574", "sub-record-check,s,1(in,434444444440,dontcare)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/TrunkProvider-00000574", "0?initialized") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/TrunkProvider-00000574", "__REC_STATUS=INITIALIZED") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/TrunkProvider-00000574", "NOW=1719440334") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/TrunkProvider-00000574", "__DAY=27") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/TrunkProvider-00000574", "__MONTH=06") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/TrunkProvider-00000574", "__YEAR=2024") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/TrunkProvider-00000574", "__TIMESTR=20240627-001854") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/TrunkProvider-00000574", "__FROMEXTEN=unknown") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/TrunkProvider-00000574", "__MON_FMT=wav") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/TrunkProvider-00000574", "Recordings initialized") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ARG3=dontcare)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/TrunkProvider-00000574", "REC_POLICY_MODE_SAVE=") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(REC_STATUS=NO)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/TrunkProvider-00000574", "2?checkaction") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/TrunkProvider-00000574", "1?sub-record-check,in,1") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,in,1)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/TrunkProvider-00000574", "Inbound Recording Check to 434444444440") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/TrunkProvider-00000574", "FROMEXTEN=unknown") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/TrunkProvider-00000574", "15?Set(FROMEXTEN=004355555555555555)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/TrunkProvider-00000574", "recordcheck,1(dontcare,in,434444444440)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/TrunkProvider-00000574", "Starting recording check against dontcare") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/TrunkProvider-00000574", "dontcare") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:3] Set("PJSIP/TrunkProvider-00000574", "CHANNEL(tonezone)=us") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:4] Set("PJSIP/TrunkProvider-00000574", "__FROM_DID=434444444440") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:5] Set("PJSIP/TrunkProvider-00000574", "returnhere=1") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:6] Gosub("PJSIP/TrunkProvider-00000574", "app-blacklist-check,s,1()") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/TrunkProvider-00000574", "0?blacklisted") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/TrunkProvider-00000574", "CALLED_BLACKLIST=1") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:7] Set("PJSIP/TrunkProvider-00000574", "CDR(did)=434444444440") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:8] GotoIf("PJSIP/TrunkProvider-00000574", "0?") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:9] ExecIf("PJSIP/TrunkProvider-00000574", "0 ?Set(CALLERID(name)=004355555555555555)") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:10] Set("PJSIP/TrunkProvider-00000574", "__MOHCLASS=") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:11] Set("PJSIP/TrunkProvider-00000574", "__REVERSAL_REJECT=FALSE") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:12] GotoIf("PJSIP/TrunkProvider-00000574", "1?post-reverse-charge") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (from-pstn-did,434444444440,14)
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:14] NoOp("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:15] Set("PJSIP/TrunkProvider-00000574", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:16] Set("PJSIP/TrunkProvider-00000574", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:17] Set("PJSIP/TrunkProvider-00000574", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:18] Set("PJSIP/TrunkProvider-00000574", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:19] Set("PJSIP/TrunkProvider-00000574", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
[2024-06-27 00:18:54] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:20] AGI("PJSIP/TrunkProvider-00000574", "agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Executing Scheme..
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Scheme Asked is: ALL
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The DID is: 434444444440
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNUM is: 004355555555555555
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNAME is: +4355555555555
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi:
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Starting scheme Default
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before strip_tags: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after strip_tags: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Strip_Accent_Characters: Y
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before stripAccents: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after stripAccents: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before preg_replace: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after preg_replace: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Character Encodings: 'ISO-8859-1'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_convert_encoding: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_convert_encoding: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller_Id_Max_Length: '60'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_substr: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_substr: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_substr: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_substr: My Name, length: 15, mb_strlen: 15
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting caller id to: My Name
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_NEW: My Name
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_OLD: +4355555555555
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Determined to be: 'My Name'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Attempting to set lookupcid
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: <PJSIP/TrunkProvider-00000574>AGI Script agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:21] Set("PJSIP/TrunkProvider-00000574", "CALLERID(name)=My Name") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:22] NoOp("PJSIP/TrunkProvider-00000574", "CallerID Entry Point") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:23] Gosub("PJSIP/TrunkProvider-00000574", "macro-prepend-cid,s,1(B:)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-prepend-cid:1] GotoIf("PJSIP/TrunkProvider-00000574", "1?REPCID") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-prepend-cid,s,5)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-prepend-cid:5] Set("PJSIP/TrunkProvider-00000574", "_RGPREFIX=B:") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-prepend-cid:6] Set("PJSIP/TrunkProvider-00000574", "CALLERID(name)=B:My Name") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-prepend-cid:7] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [434444444440@from-pstn-did:24] Goto("PJSIP/TrunkProvider-00000574", "timeconditions,2,1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (timeconditions,2,1)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:1] Set("PJSIP/TrunkProvider-00000574", "DB(TC/2/INUSESTATE)=INUSE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:2] Set("PJSIP/TrunkProvider-00000574", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2024-06-27 00:18:55] WARNING[1538787][C-00000207] taskprocessor.c: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:3] NoOp("PJSIP/TrunkProvider-00000574", "TIMENOW: 00:18,Thu,27,Jun") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:4] NoOp("PJSIP/TrunkProvider-00000574", "TIMEMATCHED: TRUE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:5] GotoIfTime("PJSIP/TrunkProvider-00000574", "00:00-23:59,sun-sat,*,*?truestate") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (timeconditions,2,14)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:14] GotoIf("PJSIP/TrunkProvider-00000574", "0?falsegoto") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:15] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(DB(TC/2)=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:16] Set("PJSIP/TrunkProvider-00000574", "DEVICE_STATE(Custom:TC2)=NOT_INUSE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:17] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [2@timeconditions:18] GotoIf("PJSIP/TrunkProvider-00000574", "1?ext-group,905,1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (ext-group,905,1)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:1] GotoIf("PJSIP/TrunkProvider-00000574", "0?cid") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:2] Set("PJSIP/TrunkProvider-00000574", "__MCGROUP=905") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:3] PlayTones("PJSIP/TrunkProvider-00000574", "ring") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:4] Progress("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:5] Gosub("PJSIP/TrunkProvider-00000574", "macro-user-callerid,s,1()") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/TrunkProvider-00000574", "TOUCH_MONITOR=1719440334.2336") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/TrunkProvider-00000574", "CHANCONTEXT=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/TrunkProvider-00000574", "CHANCONTEXT=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/TrunkProvider-00000574", "CHANEXTENCONTEXT=TrunkProvider-00000574") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/TrunkProvider-00000574", "CHANEXTEN=TrunkProvider-00000574") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/TrunkProvider-00000574", "CALLERID(number)=004355555555555") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/TrunkProvider-00000574", "AMPUSER=004355555555555") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/TrunkProvider-00000574", "HOTDESCKCHAN=TrunkProvider-00000574") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/TrunkProvider-00000574", "HOTDESKEXTEN=TrunkProvider") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/TrunkProvider-00000574", "HOTDESKCALL=0") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(HOTDESKCALL=1)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(CALLERID(name)=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/TrunkProvider-00000574", "0?report") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/TrunkProvider-00000574", "1?Set(REALCALLERIDNUM=004355555555555)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/TrunkProvider-00000574", "AMPUSER=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/TrunkProvider-00000574", "0?limit") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/TrunkProvider-00000574", "AMPUSERCIDNAME=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/TrunkProvider-00000574", "1?report") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/TrunkProvider-00000574", "Macro depricated!! To keep the same line numbers") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/TrunkProvider-00000574", "Macro depricated !! To keep the same line numbers") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/TrunkProvider-00000574", "0?continue") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/TrunkProvider-00000574", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/TrunkProvider-00000574", "__TTL=64") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/TrunkProvider-00000574", "1?continue") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/TrunkProvider-00000574", "CALLERID(number)=004355555555555") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/TrunkProvider-00000574", "CALLERID(name)=B:My Name") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/TrunkProvider-00000574", "0?cnum") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/TrunkProvider-00000574", "__MCNUM=004355555555555") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/TrunkProvider-00000574", "__MCNAME=B:My Name") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/TrunkProvider-00000574", "__MCEXTEN=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/TrunkProvider-00000574", "__MCORGCHAN=PJSIP/TrunkProvider-00000574") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/TrunkProvider-00000574", "CDR(cnam)=B:My Name") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/TrunkProvider-00000574", "CDR(cnum)=004355555555555") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:6] Gosub("PJSIP/TrunkProvider-00000574", "macro-blkvm-setifempty,s,1()") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("PJSIP/TrunkProvider-00000574", "1?init") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("PJSIP/TrunkProvider-00000574", "__BLKVM_CHANNEL=PJSIP/TrunkProvider-00000574") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("PJSIP/TrunkProvider-00000574", "SHARED(BLKVM,PJSIP/TrunkProvider-00000574)=TRUE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("PJSIP/TrunkProvider-00000574", "GOSUB_RETVAL=TRUE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-setifempty:7] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:7] GotoIf("PJSIP/TrunkProvider-00000574", "0?skipov") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:8] Gosub("PJSIP/TrunkProvider-00000574", "macro-blkvm-set,s,1(reset)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(__BLKVM_CHANNEL=PJSIP/TrunkProvider-00000574)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-set:2] Set("PJSIP/TrunkProvider-00000574", "SHARED(BLKVM,PJSIP/TrunkProvider-00000574)=TRUE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-set:3] Set("PJSIP/TrunkProvider-00000574", "GOSUB_RETVAL=TRUE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-blkvm-set:4] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:9] Set("PJSIP/TrunkProvider-00000574", "__NODEST=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:10] Set("PJSIP/TrunkProvider-00000574", "RRNODEST=") in new stack
[2024-06-27 00:18:55] WARNING[1538787][C-00000207] taskprocessor.c: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:11] Set("PJSIP/TrunkProvider-00000574", "__NODEST=905") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:12] GosubIf("PJSIP/TrunkProvider-00000574", "0?sub-rgsetcid,s,1()") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:13] Set("PJSIP/TrunkProvider-00000574", "__PICKUPMARK=905") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:14] Gosub("PJSIP/TrunkProvider-00000574", "sub-record-check,s,1(rg,905,dontcare)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/TrunkProvider-00000574", "15?initialized") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,s,10)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/TrunkProvider-00000574", "Recordings initialized") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ARG3=dontcare)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/TrunkProvider-00000574", "REC_POLICY_MODE_SAVE=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(REC_STATUS=NO)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/TrunkProvider-00000574", "2?checkaction") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/TrunkProvider-00000574", "0?sub-record-check,rg,1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:18] NoOp("PJSIP/TrunkProvider-00000574", "Generic rg Recording Check - 004355555555555 905") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:19] Gosub("PJSIP/TrunkProvider-00000574", "recordcheck,1(dontcare,rg,905)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/TrunkProvider-00000574", "Starting recording check against dontcare") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/TrunkProvider-00000574", "dontcare") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@sub-record-check:20] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:15] Set("PJSIP/TrunkProvider-00000574", "RingGroupMethod=ringall") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:16] Set("PJSIP/TrunkProvider-00000574", "__ALT_CONFIRM_MSG=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:17] GotoIf("PJSIP/TrunkProvider-00000574", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (ext-group,905,20)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [905@ext-group:20] Gosub("PJSIP/TrunkProvider-00000574", "macro-dial,s,1(20,HhTtrQ(ANSWERED_ELSEWHERE),34)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:1] NoOp("PJSIP/TrunkProvider-00000574", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:2] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ALERT_INFO=Office)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:3] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ALERT_INFO=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:4] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ALERT_INFO=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:5] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:6] AGI("PJSIP/TrunkProvider-00000574", "agi://127.0.0.1/dialparties.agi") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Caller ID name is 'B' number is '004355555555555'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: CW Ignore is:
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: CF Ignore is:
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Ringgroup confirm is :
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Methodology of ring is 'ringall'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 34 to extension map
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 34 cf is disabled
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 34 do not disturb is disabled
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/9934
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/34
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/34/sip:34@192.168.37.12:5060
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/34 to 004355555555555
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 34
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL_MODE ''
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL is:
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOLPARENT is:
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] res_agi.c: <PJSIP/TrunkProvider-00000574>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:7] GotoIf("PJSIP/TrunkProvider-00000574", "1?normdial") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-dial,s,10)
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:10] NoOp("PJSIP/TrunkProvider-00000574", "Returned from dialparties with groups to dial") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:11] NoOp("PJSIP/TrunkProvider-00000574", "ringall array ") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:12] NoOp("PJSIP/TrunkProvider-00000574", "ds= PJSIP/34/sip:34@192.168.37.12:5060,20,HhtrQ(ANSWERED_ELSEWHERE)U(macro-auto-blkvm) ") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:13] NoOp("PJSIP/TrunkProvider-00000574", "dsextra= ") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:14] Set("PJSIP/TrunkProvider-00000574", "ds=PJSIP/34/sip:34@192.168.37.12:5060,20,HhtrQ(ANSWERED_ELSEWHERE)U(macro-auto-blkvm)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:15] NoOp("PJSIP/TrunkProvider-00000574", "ds= PJSIP/34/sip:34@192.168.37.12:5060,20,HhtrQ(ANSWERED_ELSEWHERE)U(macro-auto-blkvm)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:16] Set("PJSIP/TrunkProvider-00000574", "__FMGL_DIAL=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:17] Set("PJSIP/TrunkProvider-00000574", "LOOPCNT=1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:18] Set("PJSIP/TrunkProvider-00000574", "ITER=1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:19] Set("PJSIP/TrunkProvider-00000574", "__EXTTOCALL=34") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/TrunkProvider-00000574", "__MCEXTTOCALL=34") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:21] NoOp("PJSIP/TrunkProvider-00000574", "Working with 34") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:22] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:24] Set("PJSIP/TrunkProvider-00000574", "ITER=2") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:25] GotoIf("PJSIP/TrunkProvider-00000574", "0?ndloopbegin") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:26] Gosub("PJSIP/TrunkProvider-00000574", "macro-dial-ringall-predial-hook,s,1()") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:27] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:28] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(ds=PJSIP/34/sip:34@192.168.37.12:5060,20,HhtrQ(ANSWERED_ELSEWHERE)U(macro-auto-blkvm)g)") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-dial:29] Dial("PJSIP/TrunkProvider-00000574", "PJSIP/34/sip:34@192.168.37.12:5060,20,HhtrQ(ANSWERED_ELSEWHERE)U(macro-auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] app_stack.c: PJSIP/34-00000575 Internal Gosub(func-apply-sipheaders,s,1) start
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/34-00000575", "Applying SIP Headers to channel PJSIP/34-00000575") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/34-00000575", "localchan=34-00000575") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/34-00000575", "DialMCEXT=34") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/34-00000575", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,34,1") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/34-00000575", "TECH=PJSIP") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/34-00000575", "SIPHEADERKEYS=") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:7] While("PJSIP/34-00000575", "0") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] app_while.c: Jumping to priority 13
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/34-00000575", "") in new stack
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] app_stack.c: Spawn extension (from-internal, s, 1) exited non-zero on 'PJSIP/34-00000575'
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] app_stack.c: PJSIP/34-00000575 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-06-27 00:18:55] VERBOSE[389160] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-06-27 00:18:55] VERBOSE[389160] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-06-27 00:18:55] VERBOSE[389160] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-06-27 00:18:55] VERBOSE[1538787][C-00000207] app_dial.c: Called PJSIP/34/sip:34@192.168.37.12:5060
[2024-06-27 00:18:56] VERBOSE[1538787][C-00000207] app_dial.c: PJSIP/34-00000575 is ringing
[2024-06-27 00:19:00] VERBOSE[1623872] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-06-27 00:19:00] VERBOSE[1623872] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-06-27 00:19:00] VERBOSE[1623872] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [**34@from-internal:1] Gosub("PJSIP/43-00000576", "macro-user-callerid,s,1()") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/43-00000576", "TOUCH_MONITOR=1719440340.2338") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/43-00000576", "CHANCONTEXT=") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/43-00000576", "CHANCONTEXT=") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/43-00000576", "CHANEXTENCONTEXT=43-00000576") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/43-00000576", "CHANEXTEN=43-00000576") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/43-00000576", "CALLERID(number)=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/43-00000576", "AMPUSER=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/43-00000576", "HOTDESCKCHAN=43-00000576") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/43-00000576", "HOTDESKEXTEN=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/43-00000576", "HOTDESKCALL=0") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/43-00000576", "0?Set(HOTDESKCALL=1)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/43-00000576", "0?Set(CALLERID(name)=)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/43-00000576", "0?report") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/43-00000576", "1?Set(REALCALLERIDNUM=43)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/43-00000576", "AMPUSER=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/43-00000576", "0?limit") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/43-00000576", "AMPUSERCIDNAME=Picking Extension Name") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/43-00000576", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/43-00000576", "0?report") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/43-00000576", "AMPUSERCID=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/43-00000576", "__DIAL_OPTIONS=HhTtr") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/43-00000576", "CALLERID(all)="Picking Extension Name" <43>") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/43-00000576", "0?Set(CUSDIAL=)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/43-00000576", "0?Set(CALLERID(all)="Picking Extension Name" <43>)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/43-00000576", "0?limit") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/43-00000576", "0?Set(GROUP(concurrency_limit)=43)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/43-00000576", "0?Set(CHANNEL(language)=)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/43-00000576", "Macro depricated!! To keep the same line numbers") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/43-00000576", "Macro depricated !! To keep the same line numbers") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/43-00000576", "0?continue") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/43-00000576", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/43-00000576", "__TTL=64") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/43-00000576", "1?continue") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/43-00000576", "CALLERID(number)=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/43-00000576", "CALLERID(name)=Picking Extension Name") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/43-00000576", "0?cnum") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/43-00000576", "__MCNUM=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/43-00000576", "__MCNAME=Picking Extension Name") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/43-00000576", "__MCEXTEN=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/43-00000576", "__MCORGCHAN=PJSIP/43-00000576") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/43-00000576", "CDR(cnam)=Picking Extension Name") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/43-00000576", "CDR(cnum)=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/43-00000576", "") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [**34@from-internal:2] Set("PJSIP/43-00000576", "PICKUP_EXTEN=43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [**34@from-internal:3] Pickup("PJSIP/43-00000576", "34&34@PICKUPMARK&901@from-internal&901@from-internal-xfer&901@ext-group&905@from-internal&905@from-internal-xfer&905@ext-group") in new stack
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 34@from-internal.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 34@PICKUPMARK.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 901@from-internal.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 901@from-internal-xfer.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 901@ext-group.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 905@from-internal.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 905@from-internal-xfer.
[2024-06-27 00:19:00] NOTICE[1538805][C-00000208] app_directed_pickup.c: No target channel found for 905@ext-group.
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [**34@from-internal:4] Hangup("PJSIP/43-00000576", "") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Spawn extension (from-internal, **34, 4) exited non-zero on 'PJSIP/43-00000576'
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [h@from-internal:1] Gosub("PJSIP/43-00000576", "macro-hangupcall,s,1") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-hangupcall:1] Set("PJSIP/43-00000576", "__MCVMSTATUS=") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-hangupcall:2] Gosub("PJSIP/43-00000576", "app-missedcall-hangup,s,1()") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/43-00000576", "Dialed: s") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/43-00000576", "Caller: 43") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("PJSIP/43-00000576", "0?exit") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:4] Set("PJSIP/43-00000576", "EXTENNUM=s") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:5] Set("PJSIP/43-00000576", "FEXTENNUM=s") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf("PJSIP/43-00000576", "0?exit") in new stack
[2024-06-27 00:19:00] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:7] AGI("PJSIP/43-00000576", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,PJSIP/43-00000576,,,,") in new stack
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] res_agi.c: <PJSIP/43-00000576>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@app-missedcall-hangup:8] Return("PJSIP/43-00000576", "") in new stack
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-hangupcall:3] GotoIf("PJSIP/43-00000576", "1?theend") in new stack
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-hangupcall:5] ExecIf("PJSIP/43-00000576", "0?Set(CDR(recordingfile)=)") in new stack
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx.c: Executing [s@macro-hangupcall:6] Hangup("PJSIP/43-00000576", "") in new stack
[2024-06-27 00:19:02] VERBOSE[1538805][C-00000208] pbx.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/43-00000576'
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] app_stack.c: PJSIP/34-00000575 Internal Gosub(app-missedcall-hangup,34,1) start
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:1] NoOp("PJSIP/34-00000575", "Dialed: 34") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:2] NoOp("PJSIP/34-00000575", "Caller: ") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:3] GotoIf("PJSIP/34-00000575", "0?exit") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:4] Set("PJSIP/34-00000575", "EXTENNUM=34") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:5] Set("PJSIP/34-00000575", "FEXTENNUM=34") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:6] GotoIf("PJSIP/34-00000575", "1?exit") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (app-missedcall-hangup,34,8)
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [34@app-missedcall-hangup:8] Return("PJSIP/34-00000575", "") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] app_stack.c: Spawn extension (from-internal, s, 1) exited non-zero on 'PJSIP/34-00000575'
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] app_stack.c: PJSIP/34-00000575 Internal Gosub(app-missedcall-hangup,34,1) complete GOSUB_RETVAL=
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Spawn extension (macro-dial, s, 29) exited non-zero on 'PJSIP/TrunkProvider-00000574'
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [h@macro-dial:1] Gosub("PJSIP/TrunkProvider-00000574", "macro-hangupcall,s,1()") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-hangupcall:1] Set("PJSIP/TrunkProvider-00000574", "__MCVMSTATUS=") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-hangupcall:2] Gosub("PJSIP/TrunkProvider-00000574", "app-missedcall-hangup,s,1()") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/TrunkProvider-00000574", "Dialed: s") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/TrunkProvider-00000574", "Caller: ") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("PJSIP/TrunkProvider-00000574", "0?exit") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:4] Set("PJSIP/TrunkProvider-00000574", "EXTENNUM=s") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:5] Set("PJSIP/TrunkProvider-00000574", "FEXTENNUM=s") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf("PJSIP/TrunkProvider-00000574", "0?exit") in new stack
[2024-06-27 00:19:04] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:7] AGI("PJSIP/TrunkProvider-00000574", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,PJSIP/TrunkProvider-00000574,CANCEL,,905,") in new stack
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] res_agi.c: <PJSIP/TrunkProvider-00000574>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@app-missedcall-hangup:8] Return("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-hangupcall:3] GotoIf("PJSIP/TrunkProvider-00000574", "1?theend") in new stack
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-hangupcall:5] ExecIf("PJSIP/TrunkProvider-00000574", "0?Set(CDR(recordingfile)=)") in new stack
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx.c: Executing [s@macro-hangupcall:6] Hangup("PJSIP/TrunkProvider-00000574", "") in new stack
[2024-06-27 00:19:05] VERBOSE[1538787][C-00000207] pbx.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/TrunkProvider-00000574'
Thanks for the details, we are able to reproduce the issue locally. we will get back to you asap.
that's great to here, I'm using it in production (I know I shouldn't but don't want to start a new system with a "eol" product centos, also I have more or less only debian vms so I'm really happy that freepbx now uses debian thanks for this)
Thanks a lot @HLeithner for bringing this issue to our attention.
We are on it and will provide the update asap.
Feel free to share your Freepbx 17 experience as well like is it good so far , improved then 16... Is Debian good for you security point of view etc etc, IT would be good for us to hear the feedbacks :)
Thanks
bringing this issue to our attention. The people now using it are not so happy ;-) but at least it's not my fault unable to configure it
About Debian, from my point of view, it is the perfect compromise. You have a real solid and stable base system, if you need more uptodate software you can easily create your own repo or use a 3rd party or backport. I have a VM running which provides PHP 5.2-8.3. If you only use a minimum on packages you require, upgrading every 2 years to the next major version is already done in minutes, it also means you will not run 5+ years old software like it was in centos. I'm running 100+ Debian VMs and Proxmox Clusters (also Debian) based and head no single upgrade problem which couldn't be solved since Debian etch.
From Security point of view, it's as always install as less software (attack vectors) as possible. Firewall is done by the kernel anyway, block all, allow whats needed. In my case I do this for the most VMs in the proxmox cluster firewall.
Freepbx 17 evolved also really nice even if the interface could be more user friendly but I got used to it so it's ok for me. Multi tenant would be nice but I think that's out of scope. The Debian install script also works fine (except the reported issues which have been fixed already) even on system with the bare minimum on packages. Only the firewall looks me out on installation when it gets activated but that's already reported in #247 .
thanks a lot @HLeithner for your feedback.
core version 17.0.9.73
@kguptasangoma @jissphilip can confirm this fixes the issue thanks, but since mirror.freepbx.org was broken on the weekend, the core module only provides an downgrade to 17.0.9.68 my last version was 17.0.9.72. (I patched the fix manually)
FreePBX Version
FreePBX 17
Issue Description
Installed a new system based on FreePBX 17, which works great. Except I'm unable to pickup a remote extension when the call comes from external.
Picking up an internal call works as expected.
Operating Environment
FreePBX 17 and Asterisk 21 update from today.
Relevant log output
The caller log says the extension 34 is called in contact from-internal