Pbx_functions.c: Function LANGUAGE not registered - Calls Stop

mvogel4949 · March 7, 2019, 1:54pm

I’m running Asterisk 13.17 and randomly calls just starting failing. I go into the logs and for the failed calls I see the following:

[2019-03-07 08:06:15] ERROR[3986][C-0000ca29] pbx_functions.c: Function LANGUAGE not registered

When the calls start working again this goes away. Any ideas what might be happening here?

lgaetz · March 7, 2019, 2:01pm

Need the full call trace around one of these errors. As far as I know, there is no such function as LANGUAGE:

freepbx*CLI> core show function LANGUAGE
No function by that name registered.
Command 'core show function LANGUAGE' failed.

mvogel4949 · March 7, 2019, 2:03pm

[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] Macro("SIP/300-00002cdd", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] Set("SIP/300-00002cdd", "TOUCH_MONITOR=1551964173.16295") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:2] Set("SIP/300-00002cdd", "AMPUSER=300") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] GotoIf("SIP/300-00002cdd", "0?report") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] ExecIf("SIP/300-00002cdd", "1?Set(REALCALLERIDNUM=300)") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:5] Set("SIP/300-00002cdd", "AMPUSER=300") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:6] GotoIf("SIP/300-00002cdd", "0?limit") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:7] Set("SIP/300-00002cdd", "AMPUSERCIDNAME=LetoCom Office") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:8] ExecIf("SIP/300-00002cdd", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:9] GotoIf("SIP/300-00002cdd", "0?report") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:10] Set("SIP/300-00002cdd", "AMPUSERCID=300") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:11] Set("SIP/300-00002cdd", "__DIAL_OPTIONS=Ttr") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:12] Set("SIP/300-00002cdd", "CALLERID(all)="LetoCom Office" &lt;300&gt;") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:13] GotoIf("SIP/300-00002cdd", "0?limit") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:14] ExecIf("SIP/300-00002cdd", "1?Set(GROUP(concurrency_limit)=300)") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:15] ExecIf("SIP/300-00002cdd", "0?Set(CHANNEL(language)=)") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:16] NoOp("SIP/300-00002cdd", "Macro Depth is 1") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:17] GotoIf("SIP/300-00002cdd", "1?report2:macroerror") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:18] GotoIf("SIP/300-00002cdd", "1?continue") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:37] Set("SIP/300-00002cdd", "CALLERID(number)=300") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:38] Set("SIP/300-00002cdd", "CALLERID(name)=LetoCom Office") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:39] GotoIf("SIP/300-00002cdd", "0?cnum") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]lerid:40] Set("SIP/300-00002cdd", "CDR(cnam)=LetoCom Office") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:41] Set("SIP/300-00002cdd", "CDR(cnum)=300") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:42] Set("SIP/300-00002cdd", "CHANNEL(language)=en") in new stack
[2019-03-07 08:09:33] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:2] Set("SIP/300-00002cdd", "MOHCLASS=default") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] Set("SIP/300-00002cdd", "LANGUAGE()=en") in new stack
[2019-03-07 08:09:34] ERROR[4272][C-0000ca6c] pbx_functions.c: Function LANGUAGE not registered
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] Set("SIP/300-00002cdd", "_NODEST=") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:5] NoCDR("SIP/300-00002cdd", "") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:6] Macro("SIP/300-00002cdd", "dialout-trunk,1,5183657500,,on") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] Set("SIP/300-00002cdd", "DIAL_TRUNK=1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:2] ExecIf("SIP/300-00002cdd", "0?Set(DIAL_OPTIONS=tr)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] GosubIf("SIP/300-00002cdd", "0?sub-pincheck,s,1()") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] GotoIf("SIP/300-00002cdd", "0?disabletrunk,1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:5] Set("SIP/300-00002cdd", "DIAL_NUMBER=5183657500") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:6] Set("SIP/300-00002cdd", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:7] Set("SIP/300-00002cdd", "OUTBOUND_GROUP=OUT_1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:8] Set("SIP/300-00002cdd", "DIAL_TRUNK_OPTIONS=T") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:9] GotoIf("SIP/300-00002cdd", "0?nomax") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:10] GotoIf("SIP/300-00002cdd", "0?chanfull") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:11] GotoIf("SIP/300-00002cdd", "0?skipoutcid") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:12] Macro("SIP/300-00002cdd", "outbound-callerid,1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] ExecIf("SIP/300-00002cdd", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:2] ExecIf("SIP/300-00002cdd", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] ExecIf("SIP/300-00002cdd", "0?Set(REALCALLERIDNUM=300)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] ExecIf("SIP/300-00002cdd", "0?Set(AMPUSER=)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:5] GotoIf("SIP/300-00002cdd", "1?normcid") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx_builtins.c: Goto (macro-outbound-callerid,s,9)
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:9] Set("SIP/300-00002cdd", "USEROUTCID=") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:10] Set("SIP/300-00002cdd", "EMERGENCYCID=") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:11] Set("SIP/300-00002cdd", "TRUNKOUTCID=501") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:12] GotoIf("SIP/300-00002cdd", "1?trunkcid") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx_builtins.c: Goto (macro-outbound-callerid,s,17)
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:17] ExecIf("SIP/300-00002cdd", "1?Set(CALLERID(all)=501)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:18] ExecIf("SIP/300-00002cdd", "0?Set(CALLERID(all)=)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:19] ExecIf("SIP/300-00002cdd", "0?Set(CALLERID(all)=)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:20] ExecIf("SIP/300-00002cdd", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:21] ExecIf("SIP/300-00002cdd", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:22] Set("SIP/300-00002cdd", "CDR(outbound_cnum)=501") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:23] Set("SIP/300-00002cdd", "CDR(outbound_cnam)=") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:13] GosubIf("SIP/300-00002cdd", "0?sub-flp-1,s,1()") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:14] Set("SIP/300-00002cdd", "OUTNUM=5183657500") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:15] Set("SIP/300-00002cdd", "custom=SIP/0124487") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:16] ExecIf("SIP/300-00002cdd", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:17] ExecIf("SIP/300-00002cdd", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:18] Macro("SIP/300-00002cdd", "dialout-trunk-predial-hook,") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] MacroExit("SIP/300-00002cdd", "") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:19] GotoIf("SIP/300-00002cdd", "0?skipcrm") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:20] Set("SIP/300-00002cdd", "__CRM_DIRECTION=OUTBOUND") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:21] Set("SIP/300-00002cdd", "__CRM_DESTINATION=5183657500") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:22] Set("SIP/300-00002cdd", "__CRM_SOURCE=300") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:23] AGI("SIP/300-00002cdd", "sangomacrm.agi") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] res_agi.c: &lt;SIP/300-00002cdd&gt;AGI Script sangomacrm.agi completed, returning 0
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:24] Set("SIP/300-00002cdd", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]cro-dialout-trunk:25] NoOp("SIP/300-00002cdd", "CRM Finished") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:26] GotoIf("SIP/300-00002cdd", "0?bypass,1") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:27] ExecIf("SIP/300-00002cdd", "1?Set(CONNECTEDLINE(num,i)=5183657500)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:28] ExecIf("SIP/300-00002cdd", "1?Set(CONNECTEDLINE(name,i)=CID:501)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:29] ExecIf("SIP/300-00002cdd", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)501)") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:30] GotoIf("SIP/300-00002cdd", "0?customtrunk") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:31] Dial("SIP/300-00002cdd", "SIP/0124487/5183657500,300,T") in new stack
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] netsock2.c: Using SIP RTP TOS bits 184
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] netsock2.c: Using SIP RTP CoS mark 5
[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] app_dial.c: Called SIP/0124487/5183657500
[2019-03-07 08:09:37] VERBOSE[4272][C-0000ca6c] app_dial.c: SIP/0124487-00002cde is ringing
[2019-03-07 08:09:37] VERBOSE[4272][C-0000ca6c] app_dial.c: SIP/0124487-00002cde answered SIP/300-00002cdd
[2019-03-07 08:09:37] VERBOSE[4277][C-0000ca6c] bridge_channel.c: Channel SIP/0124487-00002cde joined 'simple_bridge' basic-bridge &lt;1eb824c5-b8e5-4310-9d18-c90bab61b816&gt;
[2019-03-07 08:09:37] VERBOSE[4272][C-0000ca6c] bridge_channel.c: Channel SIP/300-00002cdd joined 'simple_bridge' basic-bridge &lt;1eb824c5-b8e5-4310-9d18-c90bab61b816&gt;
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] bridge_channel.c: Channel SIP/300-00002cdd left 'simple_bridge' basic-bridge &lt;1eb824c5-b8e5-4310-9d18-c90bab61b816&gt;
[2019-03-07 08:09:42] VERBOSE[4277][C-0000ca6c] bridge_channel.c: Channel SIP/0124487-00002cde left 'simple_bridge' basic-bridge &lt;1eb824c5-b8e5-4310-9d18-c90bab61b816&gt;
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] app_macro.c: Spawn extension (macro-dialout-trunk, s, 31) exited non-zero on 'SIP/300-00002cdd' in macro 'dialout-trunk'
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Spawn extension (from-internal, 5183657500, 6) exited non-zero on 'SIP/300-00002cdd'
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] Macro("SIP/300-00002cdd", "hangupcall") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] GotoIf("SIP/300-00002cdd", "1?theend") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] ExecIf("SIP/300-00002cdd", "0?Set(CDR(recordingfile)=)") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] Hangup("SIP/300-00002cdd", "") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/300-00002cdd' in macro 'hangupcall'
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/300-00002cdd'
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] app_stack.c: SIP/300-00002cdd Internal Gosub(crm-hangup,s,1) start
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:1] NoOp("SIP/300-00002cdd", "Sending Hangup to CRM") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:2] NoOp("SIP/300-00002cdd", "HANGUP CAUSE: 16") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] ExecIf("SIP/300-00002cdd", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:4] NoOp("SIP/300-00002cdd", "MASTER CHANNEL: 1551964173.16295 = 1551964173.16295") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:5] GotoIf("SIP/300-00002cdd", "0?return") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:6] Set("SIP/300-00002cdd", "__CRM_HANGUP=1") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:7] AGI("SIP/300-00002cdd", "sangomacrm.agi") in new stack
[2019-03-07 08:09:42] VERBOSE[4272][C-0000ca6c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-03-07 08:09:43] VERBOSE[4272][C-0000ca6c] res_agi.c: &lt;SIP/300-00002cdd&gt;AGI Script sangomacrm.agi completed, returning 0
[2019-03-07 08:09:43] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:8] Return("SIP/300-00002cdd", "") in new stack
[2019-03-07 08:09:43] VERBOSE[4272][C-0000ca6c] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/300-00002cdd'
[2019-03-07 08:09:43] VERBOSE[4272][C-0000ca6c] app_stack.c: SIP/300-00002cdd Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

lgaetz · March 7, 2019, 2:09pm

Output from the following pls:

asterisk -x "dialplan show [email protected]"

and

fwconsole ma list | grep core

mvogel4949 · March 7, 2019, 2:16pm

[ Included context 'outrt-4' created by 'pbx_config' ]
  '_NXXNXXXXXX' =>  1. Macro(user-callerid,LIMIT,EXTERNAL,)       [pbx_config]
                    2. Gosub(sub-record-check,s,1(out,${EXTEN},dontcare)) [pbx_config]
                    3. ExecIf($[ "${CALLEE_ACCOUNCODE}" != "" ] ?Set(CDR(accountcode)=${CALLEE_ACCOUNCODE})) [pbx_config]
                    4. Set(MOHCLASS=${IF($["${MOHCLASS}"=""]?top40testat10percenvolume:${MOHCLASS})}) [pbx_config]
                    5. ExecIf($["${KEEPCID}"!="TRUE" & ${LEN(${DB(AMPUSER/${AMPUSER}/outboundcid)})}=0 & ${LEN(${TRUNKCIDOVERRIDE})}=0]?Set(TRUNKCIDOVERRIDE=8452784180)) [pbx_config]
                    6. Set(_NODEST=)                              [pbx_config]
                    7. Macro(dialout-trunk,3,${EXTEN},,off)       [pbx_config]
                    8. Macro(outisbusy,)                          [pbx_config]

[ Included context 'bad-number' created by 'pbx_config' ]
  '_X.' =>          1. ResetCDR()                                 [pbx_config]
                    2. NoCDR()                                    [pbx_config]
                    3. Progress()                                 [pbx_config]
                    4. Wait(1)                                    [pbx_config]
                    5. Playback(silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer) [pbx_config]
                    6. Wait(1)                                    [pbx_config]
                    7. Congestion(20)                             [pbx_config]
                    8. Hangup()                                   [pbx_config]

-= 2 extensions (16 priorities) in 2 contexts. =-

mvogel4949 · March 7, 2019, 2:16pm

lgaetz · March 7, 2019, 2:28pm

Presumably things are all working now, have you upgraded any modules since the issue happened? Do you, or did you, have custom dialplan on this system? The call trace provided doesn’t match up with the dialplan:

3. ExecIf($[ "${CALLEE_ACCOUNCODE}" != "" ] ?Set(CDR(accountcode)=${CALLEE_ACCOUNCODE})) [pbx_config]

[2019-03-07 08:09:34] VERBOSE[4272][C-0000ca6c] pbx.c: Executing [[email protected]:3] Set("SIP/300-00002cdd", "LANGUAGE()=en") in new stack

In the problem call from-internal priority 3 was setting LANGUAGE incorrectly, but at present priority 3 sets the account code. Not sure what to tell you what might be generating the problem line.

mvogel4949 · March 7, 2019, 2:34pm

I appreciate you taking a look. It certainly is an odd one. It does appear to be working right now. Thanks!

lgaetz · March 11, 2019, 6:30pm

Hi @mvogel4949:

I touched a system today with the same issue, and in this case there was evidence of an exploit in the file /etc/asterisk/extensions_custom.conf. Make sure there is nothing there you don’t recognize.

mvogel4949 · March 11, 2019, 6:44pm

Great catch. I found this and have no idea where it came from:

[asterisk-outcalls]; reloadout
exten => _.,1,Macro(user-callerid,LIMIT,EXTERNAL,); reloadout
exten => _.,n,Set(MOHCLASS=${IF($["${MOHCLASS}"=""]?default:${MOHCLASS})}); reloadout
exten => _.,n,Set(LANGUAGE()=en); reloadout
exten => _.,n,Set(_NODEST=); reloadout
exten => _.,n,NoCDR(); reloadout
exten => _.,n,Macro(dialout-trunk,1,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,3,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,4,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,5,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,6,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,7,${EXTEN},,on); reloadout
exten => _.,n,Macro(dialout-trunk,9,${EXTEN},,on); reloadout
exten => _.,n,Macro(outisbusy,); reloadout

tm1000 · March 11, 2019, 6:47pm

This is a variant of the ‘thankuohoh’. They changed it so they wouldnt be detected by framework

lgaetz · March 11, 2019, 6:48pm

The system I touched had the FreePBX GUI, UCP as well as other 3rd party package GUIs exposed to untrusted traffic. Not sure about your case.

“The security aspect of cyber is very, very tough. And maybe, it’s hardly doable.”

mvogel4949 · March 11, 2019, 6:50pm

I was thinking it reminded me of the thankuohoh. Do this think this system was previously hit with thanku which morphed into this or hit for the first time with the new version?

woogieboogie · March 11, 2019, 7:45pm

Assuming new as the system in question (mine) was deployed last year with full updates before having ports opened which I think was after the last thankuohoh ?

woogieboogie · March 11, 2019, 7:46pm

hey mvogel4949 do you happen to have any asternic stuff installed (Asternic CDR, Asternic Queue Stats, or FOP2)

mvogel4949 · March 11, 2019, 8:36pm

It had FOP2 on it but there were no ports like 4445 being forwarded to the system to utilize it. The system did have http access open without any trusted source port forwarding setup.

woogieboogie · March 13, 2019, 4:03pm

Would love to know where the exploit was

mvogel4949 · March 13, 2019, 4:15pm

I agree. There has to be some logfile we could search to see when and how this entry was made into extensions_custom.conf file.

tm1000 · March 13, 2019, 6:51pm

Unfortunately there is not due to GDRP. Before GDRP we were logging such things on FreePBX systems but now we do not log this at all. Thank the European Union!

PitzKey · March 14, 2019, 1:28pm

I am not familiar with the law, but would it be an option to be disabled by default and if a user wants they can toggle a switch to enable it?