[2023-02-20 17:31:25] Asterisk 18.14.0 built by root @ PBX-3123.xpbx.foehn.co.uk on a x86_64 running Linux on 2023-01-10 16:21:03 UTC [2023-02-20 17:31:32] NOTICE[18250] res_pjsip_geolocation.c: PJSIP/219-astpjsip-00000006: Endpoint has no geoloc_incoming_call_profile. Done. [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Call in default with 00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Call in default with 00447700900000 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:2] Set("PJSIP/219-astpjsip-00000006", "__INSIDE=1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:3] Set("PJSIP/219-astpjsip-00000006", "__INSIDE_EXTEN=219-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:4] Set("PJSIP/219-astpjsip-00000006", "WEXTEN=00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:5] Set("PJSIP/219-astpjsip-00000006", "WTENANT=") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:6] Verbose("PJSIP/219-astpjsip-00000006", "1, WEXTEN: '00447700900000' and WTENANT: ''") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: WEXTEN: '00447700900000' and WTENANT: '' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:7] GotoIf("PJSIP/219-astpjsip-00000006", "1?set_headers") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (from-inside-astpjsip,00447700900000,9) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:9] Gosub("PJSIP/219-astpjsip-00000006", "fhn-set-variables,start,1(102,astpjsip,00447700900000)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Activate general variables") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Activate general variables [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:2] Set("PJSIP/219-astpjsip-00000006", "__TENANT_ID=102") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:3] Set("PJSIP/219-astpjsip-00000006", "__TENANT=astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:4] Set("PJSIP/219-astpjsip-00000006", "__DIALED_NUMBER=00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:5] Set("PJSIP/219-astpjsip-00000006", "__FHN_MEDIA=/opt/shared_folder/backend/xpbx/mediafiles/vrecords") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:6] Set("PJSIP/219-astpjsip-00000006", "__PEERNAME=219-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:7] ExecIf("PJSIP/219-astpjsip-00000006", "0?Set(__PEERNAME=") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:8] ExecIf("PJSIP/219-astpjsip-00000006", "0?Set(__PEERNAME=)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:9] Set("PJSIP/219-astpjsip-00000006", "__MYEXTENSION=219") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:10] Set("PJSIP/219-astpjsip-00000006", "__INITIATOR_FOR_RECORD=219-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:11] Set("PJSIP/219-astpjsip-00000006", "__DB_PREFIX=FHN/astpjsip/219") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:12] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/default-MOH,MOH") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:13] ExecIf("PJSIP/219-astpjsip-00000006", "1?Set(CHANNEL(musicclass)=working-solutions-astpjsip)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:14] ExecIf("PJSIP/219-astpjsip-00000006", "0?AGI(agi://127.0.0.1/getVariable,FHN/astpjsip/219/ws_channel,WS_CHANNEL)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:15] ExecIf("PJSIP/219-astpjsip-00000006", "0?Set(__WS_CHANNEL=)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:16] Progress("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-variables:17] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:10] Set("PJSIP/219-astpjsip-00000006", "__TRANSFER_CONTEXT=from-transfer-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:11] Set("PJSIP/219-astpjsip-00000006", "__FORWARD_CONTEXT=from-forward-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:12] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/219/MAXCALLS,MAXCALLS") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:13] Gosub("PJSIP/219-astpjsip-00000006", "check-call-limit,start,1(219-astpjsip,3,out)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:1] Verbose("PJSIP/219-astpjsip-00000006", "Check the max concurrent for '219-astpjsip'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Check the max concurrent for '219-astpjsip' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:2] Verbose("PJSIP/219-astpjsip-00000006", "MAX: '3'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: MAX: '3' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:3] Verbose("PJSIP/219-astpjsip-00000006", "TYPE OF CALL: 'out'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: TYPE OF CALL: 'out' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:4] Set("PJSIP/219-astpjsip-00000006", "GROUP(call_limit_out)=219-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:5] Set("PJSIP/219-astpjsip-00000006", "COUNTER=1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:6] Verbose("PJSIP/219-astpjsip-00000006", "Total: '1'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Total: '1' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:7] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(-1)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@check-call-limit:8] Return("PJSIP/219-astpjsip-00000006", "1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:14] ExecIf("PJSIP/219-astpjsip-00000006", "0?HangUp()") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:15] ExecIf("PJSIP/219-astpjsip-00000006", "0?Set(CALLERID(all)= <>") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:16] Set("PJSIP/219-astpjsip-00000006", "ORIG_CID="David Middleton" <219-astpjsip>") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:17] Set("PJSIP/219-astpjsip-00000006", "MY_CHAN=219-astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:18] ExecIf("PJSIP/219-astpjsip-00000006", "0?AGI(agi://127.0.0.1/sendWS,,Call to 00447700900000)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:19] Gosub("PJSIP/219-astpjsip-00000006", "set-featuremap-astpjsip,start,1()") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@set-featuremap-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000006", "1,>> Activate the featurecodes") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: >> Activate the featurecodes [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@set-featuremap-astpjsip:2] Set("PJSIP/219-astpjsip-00000006", "FEATURE(inherit)=yes") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@set-featuremap-astpjsip:3] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:20] ExecIf("PJSIP/219-astpjsip-00000006", "1?Gosub(fhn-set-monitor-vars,start,1(SRC,219-astpjsip))") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:1] Verbose("PJSIP/219-astpjsip-00000006", "Check MONITOR parameters for 'SRC': '219-astpjsip'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Check MONITOR parameters for 'SRC': '219-astpjsip' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:2] Set("PJSIP/219-astpjsip-00000006", "AUX=219") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:3] Set("PJSIP/219-astpjsip-00000006", "__SRC_REC_LABEL=219") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:4] GotoIf("PJSIP/219-astpjsip-00000006", "0?predefined:search") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,7) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:7] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/219/RECORD,TYPE_RECORD") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:8] GotoIf("PJSIP/219-astpjsip-00000006", "0?allrecord") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:9] GotoIf("PJSIP/219-astpjsip-00000006", "1?norecord") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,17) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:17] Set("PJSIP/219-astpjsip-00000006", "__SRC_REC_STATUS=no") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:18] Set("PJSIP/219-astpjsip-00000006", "__SRC_REC_ON_DEMAND=no") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:19] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:21] ExecIf("PJSIP/219-astpjsip-00000006", "1?Gosub(fhn-set-ringing-status,start,1(219-astpjsip))") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-ringing-status:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Set RINGING to extension='219-astpjsip'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Set RINGING to extension='219-astpjsip' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-ringing-status:2] ExecIf("PJSIP/219-astpjsip-00000006", "1?Set(DB(RINGTS/219-astpjsip)=1676914292)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-ringing-status:3] ExecIf("PJSIP/219-astpjsip-00000006", "1?Set(DEVICE_STATE(Custom:219-astpjsip)=RINGING)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-ringing-status:4] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-astpjsip:22] Gosub("PJSIP/219-astpjsip-00000006", "from-inside-redir-astpjsip,00447700900000,1()") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-redir-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Outgoing call with 00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Outgoing call with 00447700900000 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-redir-astpjsip:2] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/CXENABLED,CXENABLED") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-redir-astpjsip:3] GotoIf("PJSIP/219-astpjsip-00000006", "1?no_cx") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (from-inside-redir-astpjsip,00447700900000,9) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [00447700900000@from-inside-redir-astpjsip:9] Gosub("PJSIP/219-astpjsip-00000006", "fhn-dialout-trunk,start,1(00447700900000)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Block CALL-FORWARDING:") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Block CALL-FORWARDING: [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:2] Verbose("PJSIP/219-astpjsip-00000006", "1,Number to dial: 00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Number to dial: 00447700900000 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:3] Set("PJSIP/219-astpjsip-00000006", "DIALED_NUMBER=00447700900000") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:4] Gosub("PJSIP/219-astpjsip-00000006", "fhn-dialout-callerid,start,1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-callerid:1] Verbose("PJSIP/219-astpjsip-00000006", "Set callerid for user '219' in tenant 'astpjsip'") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Set callerid for user '219' in tenant 'astpjsip' [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-callerid:2] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:5] Gosub("PJSIP/219-astpjsip-00000006", "fhn-checks,start,1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Block CHECKS:") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Block CHECKS: [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:2] ExecIf("PJSIP/219-astpjsip-00000006", "1?Set(CHECK_LIMIT="yes")") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:3] Gosub("PJSIP/219-astpjsip-00000006", "fhn-check-status,start,1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Block CHECK-STATUS:") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Block CHECK-STATUS: [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:2] Verbose("PJSIP/219-astpjsip-00000006", "1, Check the status of the tenant astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Check the status of the tenant astpjsip [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:3] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/STATUS,STATUS") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:4] GotoIf("PJSIP/219-astpjsip-00000006", "1?done_status") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-check-status,start,7) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:7] Verbose("PJSIP/219-astpjsip-00000006", "1, astpjsip OK") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: astpjsip OK [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-status:8] Return("PJSIP/219-astpjsip-00000006", "1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:4] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:5] Gosub("PJSIP/219-astpjsip-00000006", "fhn-check-maxcalls,start,1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Block CHECK-MAXCALLS:") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Block CHECK-MAXCALLS: [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:2] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/MAXCALLS,MAXCALLS") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:3] GotoIf("PJSIP/219-astpjsip-00000006", "0?done_maxcalls") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:4] Set("PJSIP/219-astpjsip-00000006", "GROUP(callpaths)=astpjsip") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:5] GotoIf("PJSIP/219-astpjsip-00000006", "0?allbusy:done_maxcalls") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-check-maxcalls,start,8) [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:8] Verbose("PJSIP/219-astpjsip-00000006", "1,Active calls 1 < 20") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Active calls 1 < 20 [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-maxcalls:9] Return("PJSIP/219-astpjsip-00000006", "1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:6] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:7] GotoIf("PJSIP/219-astpjsip-00000006", "0?done") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:8] Gosub("PJSIP/219-astpjsip-00000006", "fhn-check-creditlimit,start,1") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:1] Verbose("PJSIP/219-astpjsip-00000006", "1,Block CHECK-CREDITLIMIT:") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] app_verbose.c: Block CHECK-CREDITLIMIT: [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:2] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:3] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:32] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:4] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/creditlimit,CREDITLIMIT") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:5] GotoIf("PJSIP/219-astpjsip-00000006", "1?ok_credit") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-check-creditlimit,start,17) [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:17] Verbose("PJSIP/219-astpjsip-00000006", "1,Credit limit for astpjsip:0") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Credit limit for astpjsip:0 [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-check-creditlimit:18] Return("PJSIP/219-astpjsip-00000006", "1") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:9] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-checks:10] Return("PJSIP/219-astpjsip-00000006", "1") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:6] ExecIf("PJSIP/219-astpjsip-00000006", "0?Return(1)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:7] Verbose("PJSIP/219-astpjsip-00000006", "1,Setting dialing options") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Setting dialing options [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:8] Set("PJSIP/219-astpjsip-00000006", "__OPTIONS=RtT") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:9] Verbose("PJSIP/219-astpjsip-00000006", "1,Setted options RtT") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Setted options RtT [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:10] Verbose("PJSIP/219-astpjsip-00000006", "1,Set local variables") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Set local variables [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:11] Set("PJSIP/219-astpjsip-00000006", "__MY_EXTENSION=219-astpjsip") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:12] Set("PJSIP/219-astpjsip-00000006", "__MY_ID=219-astpjsip") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:13] Verbose("PJSIP/219-astpjsip-00000006", "1,Activated local vars") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Activated local vars [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:14] Gosub("PJSIP/219-astpjsip-00000006", "fhn-set-monitor-vars,start,1(DST,00447700900000)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:1] Verbose("PJSIP/219-astpjsip-00000006", "Check MONITOR parameters for 'DST': '00447700900000'") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Check MONITOR parameters for 'DST': '00447700900000' [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:2] Set("PJSIP/219-astpjsip-00000006", "AUX=00447700900000") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:3] Set("PJSIP/219-astpjsip-00000006", "__DST_REC_LABEL=00447700900000") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:4] GotoIf("PJSIP/219-astpjsip-00000006", "0?predefined:search") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,7) [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:7] AGI("PJSIP/219-astpjsip-00000006", "agi://127.0.0.1/getVariable,FHN/astpjsip/00447700900000/RECORD,TYPE_RECORD") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:8] GotoIf("PJSIP/219-astpjsip-00000006", "0?allrecord") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:9] GotoIf("PJSIP/219-astpjsip-00000006", "0?norecord") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:10] GotoIf("PJSIP/219-astpjsip-00000006", "0?selrecord") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:11] Set("PJSIP/219-astpjsip-00000006", "__DST_REC_STATUS=no") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:12] Set("PJSIP/219-astpjsip-00000006", "__DST_REC_ON_DEMAND=no") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor-vars:13] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:15] Gosub("PJSIP/219-astpjsip-00000006", "fhn-set-monitor,start,1()") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor:1] Verbose("PJSIP/219-astpjsip-00000006", "Set MONITOR for '219' with 'no' and '00447700900000' with 'no'") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Set MONITOR for '219' with 'no' and '00447700900000' with 'no' [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor:2] Set("PJSIP/219-astpjsip-00000006", "__SEGMENT=0") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor:3] Set("PJSIP/219-astpjsip-00000006", "__MONITOR_FILENAME=219-no-00447700900000-no-20230220-173133-astpjsip") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor:4] GotoIf("PJSIP/219-astpjsip-00000006", "1?end") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx_builtins.c: Goto (fhn-set-monitor,start,9) [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-monitor:9] Return("PJSIP/219-astpjsip-00000006", "") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:16] Verbose("PJSIP/219-astpjsip-00000006", "Dialing to 00447700900000") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Dialing to 00447700900000 [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dialout-trunk:17] Gosub("PJSIP/219-astpjsip-00000006", "fhn-dial,start,1(PJSIP/00447700900000@proxy,,RtT)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dial:1] Verbose("PJSIP/219-astpjsip-00000006", "Dial for 'PJSIP/00447700900000@proxy' '' 'RtT'") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Dial for 'PJSIP/00447700900000@proxy' '' 'RtT' [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-dial:2] Dial("PJSIP/219-astpjsip-00000006", "PJSIP/00447700900000@proxy,,RtTb(fhn-set-kamailio-headers-pjsip^start^1)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_stack.c: PJSIP/proxy-00000007 Internal Gosub(fhn-set-kamailio-headers-pjsip,start,1) start [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:1] Verbose("PJSIP/proxy-00000007", "Add kamailio headers") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_verbose.c: Add kamailio headers [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:2] GotoIf("PJSIP/proxy-00000007", "0?done") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:3] Set("PJSIP/proxy-00000007", "PJSIP_HEADER(add,P-XPBX-tenant-id)=102") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:4] Set("PJSIP/proxy-00000007", "PJSIP_HEADER(add,P-XPBX-tenant)=astpjsip") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:5] Set("PJSIP/proxy-00000007", "PJSIP_HEADER(add,P-XPBX-type)=") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:6] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,P-XPBX-source-peername)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:7] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,P-XPBX-billing-cli)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:8] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,P-XPBX-rtpengine-id)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:9] ExecIf("PJSIP/proxy-00000007", "1?Set(PJSIP_HEADER(add,P-XPBX-peername)=219-astpjsip)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:10] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,P-XPBX-CID-A)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:11] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,X-WebRTCSession)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:12] ExecIf("PJSIP/proxy-00000007", "0?Set(PJSIP_HEADER(add,P-XPBX-ws-channel)=)") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:13] Return("PJSIP/proxy-00000007", "") in new stack [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_stack.c: Spawn extension (from-outside, start, 1) exited non-zero on 'PJSIP/proxy-00000007' [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_stack.c: PJSIP/proxy-00000007 Internal Gosub(fhn-set-kamailio-headers-pjsip,start,1) complete GOSUB_RETVAL= [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_dial.c: Called PJSIP/00447700900000@proxy [2023-02-20 17:31:33] VERBOSE[18250] netsock2.c: Using SIP RTP Audio TOS bits 184 [2023-02-20 17:31:33] VERBOSE[18250] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field. [2023-02-20 17:31:33] VERBOSE[18250] netsock2.c: Using SIP RTP Audio CoS mark 5 [2023-02-20 17:31:33] NOTICE[18250] res_pjsip_geolocation.c: PJSIP/proxy-00000007: Endpoint has no geoloc_outgoing_call_profile. Skipping. [2023-02-20 17:31:33] VERBOSE[24486][C-00000004] app_dial.c: PJSIP/proxy-00000007 is making progress passing it to PJSIP/219-astpjsip-00000006 [2023-02-20 17:31:37] VERBOSE[24486][C-00000004] app_dial.c: PJSIP/proxy-00000007 requested media update control 26, passing it to PJSIP/219-astpjsip-00000006 [2023-02-20 17:31:37] VERBOSE[24486][C-00000004] app_dial.c: PJSIP/proxy-00000007 answered PJSIP/219-astpjsip-00000006 [2023-02-20 17:31:38] VERBOSE[24506][C-00000004] bridge_channel.c: Channel PJSIP/proxy-00000007 joined 'simple_bridge' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:31:38] VERBOSE[24486][C-00000004] bridge_channel.c: Channel PJSIP/219-astpjsip-00000006 joined 'simple_bridge' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:31:44] NOTICE[18250] res_pjsip_geolocation.c: PJSIP/219-astpjsip-00000006: Endpoint has no geoloc_incoming_call_profile. Done. [2023-02-20 17:31:44] VERBOSE[24506][C-00000004] res_musiconhold.c: Started music on hold, class 'working-solutions-astpjsip', on channel 'PJSIP/proxy-00000007' [2023-02-20 17:32:02] NOTICE[18250] res_pjsip_geolocation.c: PJSIP/219-astpjsip-00000008: Endpoint has no geoloc_incoming_call_profile. Done. [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Call in default with 00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Call in default with 00441632960000 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:2] Set("PJSIP/219-astpjsip-00000008", "__INSIDE=1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:3] Set("PJSIP/219-astpjsip-00000008", "__INSIDE_EXTEN=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:4] Set("PJSIP/219-astpjsip-00000008", "WEXTEN=00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:5] Set("PJSIP/219-astpjsip-00000008", "WTENANT=") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:6] Verbose("PJSIP/219-astpjsip-00000008", "1, WEXTEN: '00441632960000' and WTENANT: ''") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: WEXTEN: '00441632960000' and WTENANT: '' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:7] GotoIf("PJSIP/219-astpjsip-00000008", "1?set_headers") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (from-inside-astpjsip,00441632960000,9) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:9] Gosub("PJSIP/219-astpjsip-00000008", "fhn-set-variables,start,1(102,astpjsip,00441632960000)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Activate general variables") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Activate general variables [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:2] Set("PJSIP/219-astpjsip-00000008", "__TENANT_ID=102") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:3] Set("PJSIP/219-astpjsip-00000008", "__TENANT=astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:4] Set("PJSIP/219-astpjsip-00000008", "__DIALED_NUMBER=00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:5] Set("PJSIP/219-astpjsip-00000008", "__FHN_MEDIA=/opt/shared_folder/backend/xpbx/mediafiles/vrecords") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:6] Set("PJSIP/219-astpjsip-00000008", "__PEERNAME=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:7] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(__PEERNAME=") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:8] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(__PEERNAME=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:9] Set("PJSIP/219-astpjsip-00000008", "__MYEXTENSION=219") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:10] Set("PJSIP/219-astpjsip-00000008", "__INITIATOR_FOR_RECORD=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:11] Set("PJSIP/219-astpjsip-00000008", "__DB_PREFIX=FHN/astpjsip/219") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:12] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/default-MOH,MOH") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:13] ExecIf("PJSIP/219-astpjsip-00000008", "1?Set(CHANNEL(musicclass)=working-solutions-astpjsip)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:14] ExecIf("PJSIP/219-astpjsip-00000008", "0?AGI(agi://127.0.0.1/getVariable,FHN/astpjsip/219/ws_channel,WS_CHANNEL)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:15] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(__WS_CHANNEL=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:16] Progress("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-variables:17] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:10] Set("PJSIP/219-astpjsip-00000008", "__TRANSFER_CONTEXT=from-transfer-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:11] Set("PJSIP/219-astpjsip-00000008", "__FORWARD_CONTEXT=from-forward-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:12] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/219/MAXCALLS,MAXCALLS") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:13] Gosub("PJSIP/219-astpjsip-00000008", "check-call-limit,start,1(219-astpjsip,3,out)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:1] Verbose("PJSIP/219-astpjsip-00000008", "Check the max concurrent for '219-astpjsip'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Check the max concurrent for '219-astpjsip' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:2] Verbose("PJSIP/219-astpjsip-00000008", "MAX: '3'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: MAX: '3' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:3] Verbose("PJSIP/219-astpjsip-00000008", "TYPE OF CALL: 'out'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: TYPE OF CALL: 'out' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:4] Set("PJSIP/219-astpjsip-00000008", "GROUP(call_limit_out)=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:5] Set("PJSIP/219-astpjsip-00000008", "COUNTER=2") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:6] Verbose("PJSIP/219-astpjsip-00000008", "Total: '2'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Total: '2' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:7] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(-1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@check-call-limit:8] Return("PJSIP/219-astpjsip-00000008", "1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:14] ExecIf("PJSIP/219-astpjsip-00000008", "0?HangUp()") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:15] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(CALLERID(all)= <>") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:16] Set("PJSIP/219-astpjsip-00000008", "ORIG_CID="David Middleton" <219-astpjsip>") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:17] Set("PJSIP/219-astpjsip-00000008", "MY_CHAN=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:18] ExecIf("PJSIP/219-astpjsip-00000008", "0?AGI(agi://127.0.0.1/sendWS,,Call to 00441632960000)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:19] Gosub("PJSIP/219-astpjsip-00000008", "set-featuremap-astpjsip,start,1()") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@set-featuremap-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000008", "1,>> Activate the featurecodes") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: >> Activate the featurecodes [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@set-featuremap-astpjsip:2] Set("PJSIP/219-astpjsip-00000008", "FEATURE(inherit)=yes") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@set-featuremap-astpjsip:3] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:20] ExecIf("PJSIP/219-astpjsip-00000008", "1?Gosub(fhn-set-monitor-vars,start,1(SRC,219-astpjsip))") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:1] Verbose("PJSIP/219-astpjsip-00000008", "Check MONITOR parameters for 'SRC': '219-astpjsip'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Check MONITOR parameters for 'SRC': '219-astpjsip' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:2] Set("PJSIP/219-astpjsip-00000008", "AUX=219") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:3] Set("PJSIP/219-astpjsip-00000008", "__SRC_REC_LABEL=219") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:4] GotoIf("PJSIP/219-astpjsip-00000008", "0?predefined:search") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,7) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:7] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/219/RECORD,TYPE_RECORD") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:8] GotoIf("PJSIP/219-astpjsip-00000008", "0?allrecord") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:9] GotoIf("PJSIP/219-astpjsip-00000008", "1?norecord") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,17) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:17] Set("PJSIP/219-astpjsip-00000008", "__SRC_REC_STATUS=no") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:18] Set("PJSIP/219-astpjsip-00000008", "__SRC_REC_ON_DEMAND=no") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:19] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:21] ExecIf("PJSIP/219-astpjsip-00000008", "1?Gosub(fhn-set-ringing-status,start,1(219-astpjsip))") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-ringing-status:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Set RINGING to extension='219-astpjsip'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Set RINGING to extension='219-astpjsip' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-ringing-status:2] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(DB(RINGTS/219-astpjsip)=1676914322)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-ringing-status:3] ExecIf("PJSIP/219-astpjsip-00000008", "0?Set(DEVICE_STATE(Custom:219-astpjsip)=RINGING)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-ringing-status:4] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-astpjsip:22] Gosub("PJSIP/219-astpjsip-00000008", "from-inside-redir-astpjsip,00441632960000,1()") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-redir-astpjsip:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Outgoing call with 00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Outgoing call with 00441632960000 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-redir-astpjsip:2] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/CXENABLED,CXENABLED") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-redir-astpjsip:3] GotoIf("PJSIP/219-astpjsip-00000008", "1?no_cx") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (from-inside-redir-astpjsip,00441632960000,9) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [00441632960000@from-inside-redir-astpjsip:9] Gosub("PJSIP/219-astpjsip-00000008", "fhn-dialout-trunk,start,1(00441632960000)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Block CALL-FORWARDING:") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Block CALL-FORWARDING: [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:2] Verbose("PJSIP/219-astpjsip-00000008", "1,Number to dial: 00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Number to dial: 00441632960000 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:3] Set("PJSIP/219-astpjsip-00000008", "DIALED_NUMBER=00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:4] Gosub("PJSIP/219-astpjsip-00000008", "fhn-dialout-callerid,start,1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-callerid:1] Verbose("PJSIP/219-astpjsip-00000008", "Set callerid for user '219' in tenant 'astpjsip'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Set callerid for user '219' in tenant 'astpjsip' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-callerid:2] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:5] Gosub("PJSIP/219-astpjsip-00000008", "fhn-checks,start,1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Block CHECKS:") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Block CHECKS: [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:2] ExecIf("PJSIP/219-astpjsip-00000008", "1?Set(CHECK_LIMIT="yes")") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:3] Gosub("PJSIP/219-astpjsip-00000008", "fhn-check-status,start,1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Block CHECK-STATUS:") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Block CHECK-STATUS: [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:2] Verbose("PJSIP/219-astpjsip-00000008", "1, Check the status of the tenant astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Check the status of the tenant astpjsip [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:3] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/STATUS,STATUS") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:4] GotoIf("PJSIP/219-astpjsip-00000008", "1?done_status") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-check-status,start,7) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:7] Verbose("PJSIP/219-astpjsip-00000008", "1, astpjsip OK") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: astpjsip OK [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-status:8] Return("PJSIP/219-astpjsip-00000008", "1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:4] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:5] Gosub("PJSIP/219-astpjsip-00000008", "fhn-check-maxcalls,start,1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Block CHECK-MAXCALLS:") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Block CHECK-MAXCALLS: [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:2] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/MAXCALLS,MAXCALLS") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:3] GotoIf("PJSIP/219-astpjsip-00000008", "0?done_maxcalls") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:4] Set("PJSIP/219-astpjsip-00000008", "GROUP(callpaths)=astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:5] GotoIf("PJSIP/219-astpjsip-00000008", "0?allbusy:done_maxcalls") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-check-maxcalls,start,8) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:8] Verbose("PJSIP/219-astpjsip-00000008", "1,Active calls 2 < 20") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Active calls 2 < 20 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-maxcalls:9] Return("PJSIP/219-astpjsip-00000008", "1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:6] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:7] GotoIf("PJSIP/219-astpjsip-00000008", "0?done") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:8] Gosub("PJSIP/219-astpjsip-00000008", "fhn-check-creditlimit,start,1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:1] Verbose("PJSIP/219-astpjsip-00000008", "1,Block CHECK-CREDITLIMIT:") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Block CHECK-CREDITLIMIT: [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:2] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:3] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:4] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/creditlimit,CREDITLIMIT") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:5] GotoIf("PJSIP/219-astpjsip-00000008", "1?ok_credit") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-check-creditlimit,start,17) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:17] Verbose("PJSIP/219-astpjsip-00000008", "1,Credit limit for astpjsip:0") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Credit limit for astpjsip:0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-check-creditlimit:18] Return("PJSIP/219-astpjsip-00000008", "1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:9] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-checks:10] Return("PJSIP/219-astpjsip-00000008", "1") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:6] ExecIf("PJSIP/219-astpjsip-00000008", "0?Return(1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:7] Verbose("PJSIP/219-astpjsip-00000008", "1,Setting dialing options") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Setting dialing options [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:8] Set("PJSIP/219-astpjsip-00000008", "__OPTIONS=RtT") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:9] Verbose("PJSIP/219-astpjsip-00000008", "1,Setted options RtT") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Setted options RtT [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:10] Verbose("PJSIP/219-astpjsip-00000008", "1,Set local variables") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Set local variables [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:11] Set("PJSIP/219-astpjsip-00000008", "__MY_EXTENSION=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:12] Set("PJSIP/219-astpjsip-00000008", "__MY_ID=219-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:13] Verbose("PJSIP/219-astpjsip-00000008", "1,Activated local vars") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Activated local vars [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:14] Gosub("PJSIP/219-astpjsip-00000008", "fhn-set-monitor-vars,start,1(DST,00441632960000)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:1] Verbose("PJSIP/219-astpjsip-00000008", "Check MONITOR parameters for 'DST': '00441632960000'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Check MONITOR parameters for 'DST': '00441632960000' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:2] Set("PJSIP/219-astpjsip-00000008", "AUX=00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:3] Set("PJSIP/219-astpjsip-00000008", "__DST_REC_LABEL=00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:4] GotoIf("PJSIP/219-astpjsip-00000008", "0?predefined:search") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-set-monitor-vars,start,7) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:7] AGI("PJSIP/219-astpjsip-00000008", "agi://127.0.0.1/getVariable,FHN/astpjsip/00441632960000/RECORD,TYPE_RECORD") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] res_agi.c: AGI Script agi://127.0.0.1/getVariable completed, returning 0 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:8] GotoIf("PJSIP/219-astpjsip-00000008", "0?allrecord") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:9] GotoIf("PJSIP/219-astpjsip-00000008", "0?norecord") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:10] GotoIf("PJSIP/219-astpjsip-00000008", "0?selrecord") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:11] Set("PJSIP/219-astpjsip-00000008", "__DST_REC_STATUS=no") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:12] Set("PJSIP/219-astpjsip-00000008", "__DST_REC_ON_DEMAND=no") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor-vars:13] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:15] Gosub("PJSIP/219-astpjsip-00000008", "fhn-set-monitor,start,1()") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor:1] Verbose("PJSIP/219-astpjsip-00000008", "Set MONITOR for '219' with 'no' and '00441632960000' with 'no'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Set MONITOR for '219' with 'no' and '00441632960000' with 'no' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor:2] Set("PJSIP/219-astpjsip-00000008", "__SEGMENT=0") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor:3] Set("PJSIP/219-astpjsip-00000008", "__MONITOR_FILENAME=219-no-00441632960000-no-20230220-173202-astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor:4] GotoIf("PJSIP/219-astpjsip-00000008", "1?end") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx_builtins.c: Goto (fhn-set-monitor,start,9) [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-monitor:9] Return("PJSIP/219-astpjsip-00000008", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:16] Verbose("PJSIP/219-astpjsip-00000008", "Dialing to 00441632960000") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Dialing to 00441632960000 [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dialout-trunk:17] Gosub("PJSIP/219-astpjsip-00000008", "fhn-dial,start,1(PJSIP/00441632960000@proxy,,RtT)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dial:1] Verbose("PJSIP/219-astpjsip-00000008", "Dial for 'PJSIP/00441632960000@proxy' '' 'RtT'") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Dial for 'PJSIP/00441632960000@proxy' '' 'RtT' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-dial:2] Dial("PJSIP/219-astpjsip-00000008", "PJSIP/00441632960000@proxy,,RtTb(fhn-set-kamailio-headers-pjsip^start^1)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_stack.c: PJSIP/proxy-00000009 Internal Gosub(fhn-set-kamailio-headers-pjsip,start,1) start [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:1] Verbose("PJSIP/proxy-00000009", "Add kamailio headers") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_verbose.c: Add kamailio headers [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:2] GotoIf("PJSIP/proxy-00000009", "0?done") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:3] Set("PJSIP/proxy-00000009", "PJSIP_HEADER(add,P-XPBX-tenant-id)=102") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:4] Set("PJSIP/proxy-00000009", "PJSIP_HEADER(add,P-XPBX-tenant)=astpjsip") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:5] Set("PJSIP/proxy-00000009", "PJSIP_HEADER(add,P-XPBX-type)=") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:6] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,P-XPBX-source-peername)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:7] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,P-XPBX-billing-cli)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:8] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,P-XPBX-rtpengine-id)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:9] ExecIf("PJSIP/proxy-00000009", "1?Set(PJSIP_HEADER(add,P-XPBX-peername)=219-astpjsip)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:10] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,P-XPBX-CID-A)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:11] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,X-WebRTCSession)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:12] ExecIf("PJSIP/proxy-00000009", "0?Set(PJSIP_HEADER(add,P-XPBX-ws-channel)=)") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] pbx.c: Executing [start@fhn-set-kamailio-headers-pjsip:13] Return("PJSIP/proxy-00000009", "") in new stack [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_stack.c: Spawn extension (from-outside, start, 1) exited non-zero on 'PJSIP/proxy-00000009' [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_stack.c: PJSIP/proxy-00000009 Internal Gosub(fhn-set-kamailio-headers-pjsip,start,1) complete GOSUB_RETVAL= [2023-02-20 17:32:02] VERBOSE[24570][C-00000005] app_dial.c: Called PJSIP/00441632960000@proxy [2023-02-20 17:32:02] VERBOSE[18250] netsock2.c: Using SIP RTP Audio TOS bits 184 [2023-02-20 17:32:02] VERBOSE[18250] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field. [2023-02-20 17:32:02] VERBOSE[18250] netsock2.c: Using SIP RTP Audio CoS mark 5 [2023-02-20 17:32:02] NOTICE[18250] res_pjsip_geolocation.c: PJSIP/proxy-00000009: Endpoint has no geoloc_outgoing_call_profile. Skipping. [2023-02-20 17:32:03] VERBOSE[24570][C-00000005] app_dial.c: PJSIP/proxy-00000009 is ringing [2023-02-20 17:32:03] VERBOSE[24570][C-00000005] app_dial.c: PJSIP/proxy-00000009 is making progress passing it to PJSIP/219-astpjsip-00000008 [2023-02-20 17:32:07] VERBOSE[24570][C-00000005] app_dial.c: PJSIP/proxy-00000009 requested media update control 26, passing it to PJSIP/219-astpjsip-00000008 [2023-02-20 17:32:07] VERBOSE[24570][C-00000005] app_dial.c: PJSIP/proxy-00000009 answered PJSIP/219-astpjsip-00000008 [2023-02-20 17:32:07] VERBOSE[24882][C-00000005] bridge_channel.c: Channel PJSIP/proxy-00000009 joined 'simple_bridge' basic-bridge [2023-02-20 17:32:07] VERBOSE[24570][C-00000005] bridge_channel.c: Channel PJSIP/219-astpjsip-00000008 joined 'simple_bridge' basic-bridge [2023-02-20 17:32:19] VERBOSE[24930] bridge_channel.c: Channel PJSIP/219-astpjsip-00000006 left 'native_rtp' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:32:19] VERBOSE[24930] bridge_channel.c: Channel PJSIP/219-astpjsip-0000000a swapped with PJSIP/219-astpjsip-00000006 into 'native_rtp' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:32:19] VERBOSE[24486][C-00000004] pbx.c: Spawn extension (fhn-dial, start, 2) exited non-zero on 'PJSIP/219-astpjsip-00000006' [2023-02-20 17:32:35] VERBOSE[24506][C-00000004] res_musiconhold.c: Stopped music on hold on PJSIP/proxy-00000007 [2023-02-20 17:32:35] VERBOSE[24506][C-00000004] bridge_channel.c: Channel PJSIP/proxy-00000007 left 'native_rtp' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:32:35] VERBOSE[24930] bridge_channel.c: Channel PJSIP/219-astpjsip-0000000a left 'native_rtp' basic-bridge <437ade56-4bc3-4a0d-9e85-8946cb6094cb> [2023-02-20 17:32:39] VERBOSE[24570][C-00000005] bridge_channel.c: Channel PJSIP/219-astpjsip-00000008 left 'native_rtp' basic-bridge [2023-02-20 17:32:39] VERBOSE[24570][C-00000005] pbx.c: Spawn extension (fhn-dial, start, 2) exited non-zero on 'PJSIP/219-astpjsip-00000008' [2023-02-20 17:32:39] VERBOSE[24882][C-00000005] bridge_channel.c: Channel PJSIP/proxy-00000009 left 'native_rtp' basic-bridge