Part of the log file with problem number 1234
[2024-11-05 09:56:37] VERBOSE[2065] chan_iax2.c: Accepting UNAUTHENTICATED call from 172.25.124.2:1052:
> requested format = ulaw,
> requested prefs = (ulaw|alaw),
> actual format = ulaw,
> host prefs = (ulaw|alaw),
> priority = mine
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [177@from-trunk:1] GotoIf("IAX2/172.25.124.2:1052-11920", "1?ext-local,177,1:followme-check,177,1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (ext-local,177,1)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [177@ext-local:1] Set("IAX2/172.25.124.2:1052-11920", "__RINGTIMER=15") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [177@ext-local:2] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(__CWIGNORE=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [177@ext-local:3] Macro("IAX2/172.25.124.2:1052-11920", "exten-vm,novm,177,0,0,0") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:1] Macro("IAX2/172.25.124.2:1052-11920", "user-callerid,") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:1] Set("IAX2/172.25.124.2:1052-11920", "TOUCH_MONITOR=1730800597.1410") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:2] Set("IAX2/172.25.124.2:1052-11920", "CHANCONTEXT=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:3] Set("IAX2/172.25.124.2:1052-11920", "CHANCONTEXT=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:4] Set("IAX2/172.25.124.2:1052-11920", "CHANEXTENCONTEXT=172.25.124.2:1052-11920") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:5] Set("IAX2/172.25.124.2:1052-11920", "CHANEXTEN=172.25.124.2:1052-11920") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:6] Set("IAX2/172.25.124.2:1052-11920", "CALLERID(number)=1052-11920:1234") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:7] Set("IAX2/172.25.124.2:1052-11920", "AMPUSER=1052-11920") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:8] Set("IAX2/172.25.124.2:1052-11920", "HOTDESCKCHAN=172.25.124.2:1052-11920") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:9] Set("IAX2/172.25.124.2:1052-11920", "HOTDESKEXTEN=172.25.124.2:1052") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:10] Set("IAX2/172.25.124.2:1052-11920", "HOTDESKCALL=0") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(HOTDESKCALL=1)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(CALLERID(name)=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("IAX2/172.25.124.2:1052-11920", "0?report") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("IAX2/172.25.124.2:1052-11920", "1?Set(REALCALLERIDNUM=1052-11920:1234)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:15] Set("IAX2/172.25.124.2:1052-11920", "AMPUSER=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("IAX2/172.25.124.2:1052-11920", "0?limit") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:17] Set("IAX2/172.25.124.2:1052-11920", "AMPUSERCIDNAME=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("IAX2/172.25.124.2:1052-11920", "1?report") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:28] NoOp("IAX2/172.25.124.2:1052-11920", "Macro Depth is 2") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("IAX2/172.25.124.2:1052-11920", "1?report2:macroerror") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("IAX2/172.25.124.2:1052-11920", "0?continue") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("IAX2/172.25.124.2:1052-11920", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:32] Set("IAX2/172.25.124.2:1052-11920", "__TTL=64") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("IAX2/172.25.124.2:1052-11920", "1?continue") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:49] Set("IAX2/172.25.124.2:1052-11920", "CALLERID(number)=1052-11920:1234") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:50] Set("IAX2/172.25.124.2:1052-11920", "CALLERID(name)=Балашов А.А.") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("IAX2/172.25.124.2:1052-11920", "0?cnum") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:52] Set("IAX2/172.25.124.2:1052-11920", "CDR(cnam)=Балашов А.А.") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:53] Set("IAX2/172.25.124.2:1052-11920", "CDR(cnum)=1052-11920:1234") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-user-callerid:54] Set("IAX2/172.25.124.2:1052-11920", "CHANNEL(language)=en") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:2] Set("IAX2/172.25.124.2:1052-11920", "RingGroupMethod=none") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:3] Set("IAX2/172.25.124.2:1052-11920", "__EXTTOCALL=177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:4] Set("IAX2/172.25.124.2:1052-11920", "__PICKUPMARK=177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:5] Set("IAX2/172.25.124.2:1052-11920", "RT=") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Gosub(ext-intercom,*80177,1())") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("IAX2/172.25.124.2:1052-11920", "0?ChanSpy(SIP/177,q)") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] WARNING[92491][C-0000028b] chan_sip.c: This function can only be used on SIP channels.
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Gosub(ext-intercom,*80177,1())") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("IAX2/172.25.124.2:1052-11920", "0?ChanSpy(SIP/177,q)") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("IAX2/172.25.124.2:1052-11920", "0?MacroExit()") in new stack
[2024-11-05 09:56:37] ERROR[92491][C-0000028b] pbx_functions.c: Function PJSIP_HEADER not registered
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:18] GotoIf("IAX2/172.25.124.2:1052-11920", "0?startcheck:exitcheck") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-exten-vm,s,37)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:37] NoOp("IAX2/172.25.124.2:1052-11920", "Exiting Checks") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:38] Gosub("IAX2/172.25.124.2:1052-11920", "sub-record-check,s,1(exten,177,dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:1] GotoIf("IAX2/172.25.124.2:1052-11920", "0?initialized") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:2] Set("IAX2/172.25.124.2:1052-11920", "__REC_STATUS=INITIALIZED") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:3] Set("IAX2/172.25.124.2:1052-11920", "NOW=1730800597") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:4] Set("IAX2/172.25.124.2:1052-11920", "__DAY=05") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:5] Set("IAX2/172.25.124.2:1052-11920", "__MONTH=11") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:6] Set("IAX2/172.25.124.2:1052-11920", "__YEAR=2024") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:7] Set("IAX2/172.25.124.2:1052-11920", "__TIMESTR=20241105-095637") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:8] Set("IAX2/172.25.124.2:1052-11920", "__FROMEXTEN=1052-11920") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:9] Set("IAX2/172.25.124.2:1052-11920", "__MON_FMT=wav") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:10] NoOp("IAX2/172.25.124.2:1052-11920", "Recordings initialized") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:11] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ARG3=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:12] Set("IAX2/172.25.124.2:1052-11920", "REC_POLICY_MODE_SAVE=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:13] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(REC_STATUS=NO)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:14] GotoIf("IAX2/172.25.124.2:1052-11920", "5?checkaction") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@sub-record-check:17] GotoIf("IAX2/172.25.124.2:1052-11920", "1?sub-record-check,exten,1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:1] NoOp("IAX2/172.25.124.2:1052-11920", "Exten Recording Check between 1052-11920 and 177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:2] Set("IAX2/172.25.124.2:1052-11920", "CALLTYPE=internal") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:3] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(CALLTYPE=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:4] Set("IAX2/172.25.124.2:1052-11920", "CALLEE=dontcare") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:5] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(CALLEE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:6] GotoIf("IAX2/172.25.124.2:1052-11920", "0?callee") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:7] GotoIf("IAX2/172.25.124.2:1052-11920", "1?caller") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:13] Set("IAX2/172.25.124.2:1052-11920", "RECMODE=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:14] Set("IAX2/172.25.124.2:1052-11920", "CALLERRECMODE=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:15] Set("IAX2/172.25.124.2:1052-11920", "CALEERECMODE=dontcare") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:16] GotoIf("IAX2/172.25.124.2:1052-11920", "0?processnormal") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:17] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:18] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:19] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:20] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:21] ExecIf("IAX2/172.25.124.2:1052-11920", "1?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:22] ExecIf("IAX2/172.25.124.2:1052-11920", "1?Set(RECMODE=dontcare)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:23] Gosub("IAX2/172.25.124.2:1052-11920", "recordcheck,1(dontcare,internal,177)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("IAX2/172.25.124.2:1052-11920", "Starting recording check against dontcare") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("IAX2/172.25.124.2:1052-11920", "dontcare") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [recordcheck@sub-record-check:3] Return("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [exten@sub-record-check:24] Return("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:39] GotoIf("IAX2/172.25.124.2:1052-11920", "1?macrodial") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-exten-vm,s,45)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:45] GosubIf("IAX2/172.25.124.2:1052-11920", "0?clrheader,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-exten-vm:46] Macro("IAX2/172.25.124.2:1052-11920", "dial-one,,HhTtr,177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:1] Set("IAX2/172.25.124.2:1052-11920", "DEXTEN=177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:2] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(__EXTTOCALL=177)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:3] Set("IAX2/172.25.124.2:1052-11920", "DIALSTATUS_CW=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:4] GosubIf("IAX2/172.25.124.2:1052-11920", "0?screen,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:5] GosubIf("IAX2/172.25.124.2:1052-11920", "0?cf,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:6] GotoIf("IAX2/172.25.124.2:1052-11920", "1?skip1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:9] GotoIf("IAX2/172.25.124.2:1052-11920", "0?nodial") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:10] GotoIf("IAX2/172.25.124.2:1052-11920", "0?continue") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:11] Set("IAX2/172.25.124.2:1052-11920", "EXTHASCW=ENABLED") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:12] GotoIf("IAX2/172.25.124.2:1052-11920", "0?next1:cwinusebusy") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:24] GotoIf("IAX2/172.25.124.2:1052-11920", "0?next3:continue") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:26] GotoIf("IAX2/172.25.124.2:1052-11920", "0?nodial") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:27] GosubIf("IAX2/172.25.124.2:1052-11920", "1?dstring,1():dlocal,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:1] Set("IAX2/172.25.124.2:1052-11920", "DSTRING=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:2] Set("IAX2/172.25.124.2:1052-11920", "DEVICES=177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Return()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(DEVICES=77)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:5] Set("IAX2/172.25.124.2:1052-11920", "LOOPCNT=1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:6] Set("IAX2/172.25.124.2:1052-11920", "ITER=1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:7] Set("IAX2/172.25.124.2:1052-11920", "THISDIAL=SIP/177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("IAX2/172.25.124.2:1052-11920", "1?docheck") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("IAX2/172.25.124.2:1052-11920", "0?skipset") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:15] Set("IAX2/172.25.124.2:1052-11920", "DSTRING=SIP/177&") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:16] Set("IAX2/172.25.124.2:1052-11920", "ITER=2") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("IAX2/172.25.124.2:1052-11920", "0?begin") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Return()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:19] Set("IAX2/172.25.124.2:1052-11920", "DSTRING=SIP/177") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [dstring@macro-dial-one:20] Return("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:28] GotoIf("IAX2/172.25.124.2:1052-11920", "0?nodial") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:29] GotoIf("IAX2/172.25.124.2:1052-11920", "0?skiptrace") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:30] GosubIf("IAX2/172.25.124.2:1052-11920", "0?ctset,1():ctclear,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [ctclear@macro-dial-one:1] NoOp("IAX2/172.25.124.2:1052-11920", "Deleting: CALLTRACE/177 139") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [ctclear@macro-dial-one:2] Return("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:31] Set("IAX2/172.25.124.2:1052-11920", "D_OPTIONS=HhTtr") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:32] GosubIf("IAX2/172.25.124.2:1052-11920", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:33] NoOp("IAX2/172.25.124.2:1052-11920", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:34] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ALERT_INFO=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:35] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ALERT_INFO=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:36] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ALERT_INFO=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:37] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:38] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:39] GosubIf("IAX2/172.25.124.2:1052-11920", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:40] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:41] GosubIf("IAX2/172.25.124.2:1052-11920", "0?qwait,1()") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:42] Set("IAX2/172.25.124.2:1052-11920", "__CWIGNORE=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:43] Set("IAX2/172.25.124.2:1052-11920", "__KEEPCID=TRUE") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:44] GotoIf("IAX2/172.25.124.2:1052-11920", "0?usegoto,1") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:45] GotoIf("IAX2/172.25.124.2:1052-11920", "1?godial") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx_builtins.c: Goto (macro-dial-one,s,50)
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:50] Macro("IAX2/172.25.124.2:1052-11920", "dialout-one-predial-hook,") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:51] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(D_OPTIONS=HhtrI)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:52] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:53] NoOp("IAX2/172.25.124.2:1052-11920", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:54] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(D_OPTIONS=Hhtr)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:55] ExecIf("IAX2/172.25.124.2:1052-11920", "0?Set(D_OPTIONS=HhTtrg)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@macro-dial-one:56] Dial("IAX2/172.25.124.2:1052-11920", "SIP/177,,HhTtrb(func-apply-sipheaders^s^1)") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] netsock2.c: Using SIP RTP TOS bits 184
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] netsock2.c: Using SIP RTP CoS mark 5
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] app_stack.c: SIP/177-000003e8 Internal Gosub(func-apply-sipheaders,s,1) start
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/177-000003e8", "Applying SIP Headers to channel SIP/177-000003e8") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/177-000003e8", "TECH=SIP") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/177-000003e8", "SIPHEADERKEYS=") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@func-apply-sipheaders:4] While("SIP/177-000003e8", "0") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] app_while.c: Jumping to priority 10
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] pbx.c: Executing [s@func-apply-sipheaders:11] Return("SIP/177-000003e8", "") in new stack
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] app_stack.c: Spawn extension (from-internal, 177, 1) exited non-zero on 'SIP/177-000003e8'
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] app_stack.c: SIP/177-000003e8 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-11-05 09:56:37] VERBOSE[92491][C-0000028b] app_dial.c: Called SIP/177