Asterisk SVN-branch-1.4-r57914M, Copyright (C) 1999 - 2006 Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'core show license' for details. ========================================================================= == Parsing '/etc/asterisk/asterisk.conf': Found == Parsing '/etc/asterisk/extconfig.conf': Found == Binding iaxusers to odbc/asterisk/iaxfriends == Binding iaxpeers to odbc/asterisk/iaxfriends == Binding sipusers to odbc/asterisk/sipfriends == Binding voicemail to odbc/asterisk/ast_voicemail == Binding queues to odbc/asterisk/ast_queues == Binding queue_members to odbc/asterisk/ast_queue_members == Parsing '/etc/asterisk/logger.conf': Found Asterisk Event Logger Started /var/log/asterisk/event_log Asterisk Dynamic Loader Starting: == Parsing '/etc/asterisk/modules.conf': Found == Parsing '/etc/asterisk/dnsmgr.conf': Found == Manager registered action Ping == Manager registered action Events == Manager registered action Logoff == Manager registered action Hangup == Manager registered action Status == Manager registered action Setvar == Manager registered action Getvar == Manager registered action GetConfig == Manager registered action UpdateConfig == Manager registered action Redirect == Manager registered action Originate == Manager registered action Command == Manager registered action ExtensionState == Manager registered action AbsoluteTimeout == Manager registered action MailboxStatus == Manager registered action MailboxCount == Manager registered action ListCommands == Manager registered action UserEvent == Manager registered action WaitEvent == Parsing '/etc/asterisk/manager.conf': Found Asterisk Management interface listening on port 5038 == Parsing '/etc/asterisk/cdr.conf': Found [Mar 8 10:37:14] NOTICE[8805]: cdr.c:1093 do_reload: CDR simple logging enabled. == Parsing '/etc/asterisk/rtp.conf': Found == RTP Allocating from port range 10000 -> 20000 == Parsing '/etc/asterisk/udptl.conf': Found == UDPTL allocating from port range 4000 -> 4999 Asterisk PBX Core Initializing Registering builtin applications: [Answer] == Registered application 'Answer' [BackGround] == Registered application 'BackGround' [Busy] == Registered application 'Busy' [Congestion] == Registered application 'Congestion' [Goto] == Registered application 'Goto' [GotoIf] == Registered application 'GotoIf' [GotoIfTime] == Registered application 'GotoIfTime' [ExecIfTime] == Registered application 'ExecIfTime' [Hangup] == Registered application 'Hangup' [NoOp] == Registered application 'NoOp' [Progress] == Registered application 'Progress' [ResetCDR] == Registered application 'ResetCDR' [Ringing] == Registered application 'Ringing' [SayNumber] == Registered application 'SayNumber' [SayDigits] == Registered application 'SayDigits' [SayAlpha] == Registered application 'SayAlpha' [SayPhonetic] == Registered application 'SayPhonetic' [SetAMAFlags] == Registered application 'SetAMAFlags' [SetGlobalVar] == Registered application 'SetGlobalVar' [Set] == Registered application 'Set' [ImportVar] == Registered application 'ImportVar' [Wait] == Registered application 'Wait' [WaitExten] == Registered application 'WaitExten' == Manager registered action DBGet == Manager registered action DBPut == Parsing '/etc/asterisk/enum.conf': Found Asterisk Dynamic Loader Starting: == Parsing '/etc/asterisk/modules.conf': Found [Mar 8 10:37:14] NOTICE[8805]: loader.c:799 load_modules: 142 modules will be loaded. == Registered application 'MusicOnHold' == Registered application 'WaitMusicOnHold' == Registered application 'SetMusicOnHold' == Registered application 'StartMusicOnHold' == Registered application 'StopMusicOnHold' == Parsing '/etc/asterisk/musiconhold.conf': Found [Mar 8 10:37:14] WARNING[8805]: res_musiconhold.c:783 moh_scan_files: Cannot open dir /var/lib/asterisk/moh/demo_q_1 or dir does not exist res_musiconhold.so => (Music On Hold Resource) [Mar 8 10:37:14] NOTICE[8805]: config.c:1184 ast_config_engine_register: Registered Config Engine odbc res_config_odbc loaded. res_config_odbc.so => (ODBC Configuration) == Parsing '/etc/asterisk/indications.conf': Found -- Registered indication country 'at' -- Registered indication country 'au' -- Registered indication country 'bg' -- Registered indication country 'br' -- Registered indication country 'be' -- Registered indication country 'ch' -- Registered indication country 'cl' -- Registered indication country 'cn' -- Registered indication country 'cz' -- Registered indication country 'de' -- Registered indication country 'dk' -- Registered indication country 'ee' -- Registered indication country 'es' -- Registered indication country 'fi' -- Registered indication country 'fr' -- Registered indication country 'gr' -- Registered indication country 'hu' -- Registered indication country 'il' -- Registered indication country 'in' -- Registered indication country 'it' -- Registered indication country 'lt' -- Registered indication country 'jp' -- Registered indication country 'mx' -- Registered indication country 'my' -- Registered indication country 'nl' -- Registered indication country 'no' -- Registered indication country 'nz' -- Registered indication country 'pl' -- Registered indication country 'pt' -- Registered indication country 'ru' -- Registered indication country 'se' -- Registered indication country 'sg' -- Registered indication country 'th' -- Registered indication country 'uk' -- Registered indication country 'us' -- Registered indication country 'us-o' -- Registered indication country 'tw' -- Registered indication country 've' -- Registered indication country 'za' -- Setting default indication country to 'us' == Registered application 'PlayTones' == Registered application 'StopPlayTones' res_indications.so => (Indications Resource) == Registered application 'Bridge' == Parsing '/etc/asterisk/features.conf': Found == Remapping feature One Touch Monitor (automon) to sequence '*1' -- Registered extension context 'parkedcalls' -- Added extension '700' priority 1 to parkedcalls == Registered application 'ParkedCall' == Registered application 'Park' == Manager registered action ParkedCalls == Manager registered action Park == Manager registered action Bridge res_features.so => (Call Features Resource) [Mar 8 10:37:14] NOTICE[8805]: res_smdi.c:539 smdi_load: Unable to load config smdi.conf: SMDI disabled [Mar 8 10:37:14] WARNING[8805]: res_smdi.c:722 load_module: No SMDI interfaces are available to listen on, not starting SDMI listener. == Parsing '/etc/asterisk/res_odbc.conf': Found [Mar 8 10:37:14] NOTICE[8805]: res_odbc.c:502 odbc_obj_connect: Connecting asterisk [Mar 8 10:37:14] NOTICE[8805]: res_odbc.c:516 odbc_obj_connect: res_odbc: Connected to asterisk [PostgreSQL-asterisk] [Mar 8 10:37:14] NOTICE[8805]: res_odbc.c:301 load_odbc_config: Registered ODBC class 'asterisk' dsn->[PostgreSQL-asterisk] [Mar 8 10:37:14] NOTICE[8805]: res_odbc.c:678 load_module: res_odbc loaded. res_odbc.so => (ODBC Resource) res_adsi.so => (ADSI Resource) == Registered application 'Monitor' == Registered application 'StopMonitor' == Registered application 'ChangeMonitor' == Registered application 'PauseMonitor' == Registered application 'UnpauseMonitor' == Manager registered action Monitor == Manager registered action StopMonitor == Manager registered action ChangeMonitor == Manager registered action PauseMonitor == Manager registered action UnpauseMonitor res_monitor.so => (Call Monitoring Resource) -- Loaded PRIVATE key 'miami1' -- Loaded PUBLIC key 'iaxtel' -- Loaded PRIVATE key 'tampa99' -- Loaded PUBLIC key 'tampa2' -- Loaded PUBLIC key 'freeworlddialup' -- Loaded PUBLIC key 'tampa99' -- Loaded PRIVATE key 'tampa2' -- Loaded PRIVATE key 'tampa3' -- Loaded PUBLIC key 'tampa3' -- Loaded PUBLIC key 'miami1' res_crypto.so => (Cryptographic Digital Signatures) == Registered custom function URIDECODE == Registered custom function URIENCODE func_uri.so => (URI encode/decode dialplan functions) == Registered application 'Milliwatt' app_milliwatt.so => (Digital Milliwatt (mu-law) Test Application) == Registered application 'Directory' app_directory.so => (Extension Directory) == Registered application 'ReadFile' app_readfile.so => (Stores output of file into a variable) == Parsing '/etc/asterisk/cdr_manager.conf': Found cdr_manager.so => (Asterisk Manager Interface CDR Backend) == Parsing '/etc/asterisk/meetme.conf': Found == Parsing '/etc/asterisk/sla.conf': Found [Mar 8 10:37:14] WARNING[8805]: app_meetme.c:4639 sla_load_config: Invalid entry in sla.conf defined with no type! [Mar 8 10:37:14] WARNING[8805]: app_meetme.c:4639 sla_load_config: Invalid entry in sla.conf defined with no type! == Manager registered action MeetmeMute == Manager registered action MeetmeUnmute == Registered application 'MeetMeAdmin' == Registered application 'MeetMeCount' == Registered application 'MeetMe' == Registered application 'SLAStation' == Registered application 'SLATrunk' app_meetme.so => (MeetMe conference bridge) == Registered file format iLBC, extension(s) ilbc format_ilbc.so => (Raw iLBC data) == Registered application 'ADSIProg' app_adsiprog.so => (Asterisk ADSI Programming Application) == Registered application 'Morsecode' app_morsecode.so => (Morse code) == Registered application 'PrivacyManager' app_privacy.so => (Require phone number to be entered, if no CallerID sent) == Registered file format pcm, extension(s) pcm|ulaw|ul|mu == Registered file format alaw, extension(s) alaw|al == Registered file format au, extension(s) au == Registered file format g722, extension(s) g722 format_pcm.so => (Raw/Sun uLaw/ALaw 8KHz Audio support (PCM,PCMA,AU) and G.722 16Khz Audio Support) == Registered file format g723sf, extension(s) g723|g723sf format_g723.so => (G.723.1 Simple Timestamp File Format) == Registered custom function CHANNEL func_channel.so => (Channel information dialplan function) == Registered custom function TIMEOUT func_timeout.so => (Channel timeout dialplan functions) == Registered application 'ZapScan' app_zapscan.so => (Scan Zap channels application) [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:400 load_module: G.729 transcoding module Copyright (C) 1999-2006 Digium, Inc. [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:401 load_module: This module is supplied under a commercial license granted by Digium, Inc. [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:402 load_module: Please see the full license text supplied by the accompanying [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:403 load_module: "register" utility, or ask for a copy from Digium. [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:404 load_module: This product includes software developed by the OpenSSL Project [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:405 load_module: for use in the OpenSSL Toolkit. (http://www.openssl.org/) [Mar 8 10:37:14] NOTICE[8805]: codec_g729.c:406 load_module: Copyright (C) 1998-2006 The OpenSSL Project == G.729 Host-ID: e2:6e:78:dd:26:b3:96:3a:a2:97:46:b1:86:5b:f3:e9:7a:ce:7e:d3 == Found license 'G729-3BD26EBE' providing 24 channels == Found total of 24 G.729 licenses == Registered translator 'g729tolin' from format g729 to slin, cost 2 == Registered translator 'lintog729' from format slin to g729, cost 9 codec_g729a.so => (Annex A/B (floating point) G.729 Codec (optimized for i686)) == Registered custom function CDR func_cdr.so => (CDR dialplan function) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_adpcm: using generic PLC [Mar 8 10:37:14] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 codec_adpcm.so => (Adaptive Differential PCM Coder/Decoder) == Registered custom function VMCOUNT == Registered application 'HasVoicemail' == Registered application 'HasNewVoicemail' app_hasnewvoicemail.so => (Indicator for whether a voice mailbox has messages in a given folder.) == Registered custom function LANGUAGE func_language.so => (Channel language dialplan function) == Manager registered action PlayDTMF == Registered application 'SendDTMF' app_senddtmf.so => (Send DTMF digits Application) == Parsing '/etc/asterisk/cdr_odbc.conf': Found [Mar 8 10:37:14] DEBUG[8805]: cdr_odbc.c:309 odbc_load_module: cdr_odbc: Logging uniqueid [Mar 8 10:37:14] DEBUG[8805]: cdr_odbc.c:322 odbc_load_module: cdr_odbc: Logging in GMT -- cdr_odbc: dsn is PostgreSQL-asterisk -- cdr_odbc: retreiving username and password from odbc config -- cdr_odbc: table is cdr cdr_odbc.so => (ODBC CDR Backend) == Registered file format gsm, extension(s) gsm format_gsm.so => (Raw GSM data) == Registered application 'ZapBarge' app_zapbarge.so => (Barge in on Zap channel application) == Registered application 'Echo' app_echo.so => (Simple Echo Application) == Parsing '/etc/asterisk/cdr_custom.conf': Found cdr_custom.so => (Customizable Comma Separated Values CDR Backend) == Registered application 'ExternalIVR' app_externalivr.so => (External IVR Interface Application) == Registered application 'SendURL' app_url.so => (Send URL Applications) pbx_loopback.so => (Loopback Switch) == Registered application 'WaitForSilence' app_waitforsilence.so => (Wait For Silence) == Registered application 'LookupCIDName' app_lookupcidname.so => (Look up CallerID Name from local database) == Registered application 'SetCDRUserField' == Registered application 'AppendCDRUserField' == Manager registered action SetCDRUserField app_setcdruserfield.so => (CDR user field apps) == Registered custom function GROUP_COUNT == Registered custom function GROUP_MATCH_COUNT == Registered custom function GROUP_LIST == Registered custom function GROUP func_groupcount.so => (Channel group dialplan functions) == Registered channel type 'Feature' (Feature Proxy Channel Driver) chan_features.so => (Feature Proxy Channel) == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 codec_a_mu.so => (A-law and Mulaw direct Coder/Decoder) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_g726: using generic PLC [Mar 8 10:37:14] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'g726tolin' from format g726 to slin, cost 3 == Registered translator 'lintog726' from format slin to g726, cost 3 [Mar 8 10:37:14] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'g726aal2tolin' from format g726aal2 to slin, cost 3 == Registered translator 'lintog726aal2' from format slin to g726aal2, cost 3 == Registered translator 'g726aal2tog726' from format g726aal2 to g726, cost 1 == Registered translator 'g726tog726aal2' from format g726 to g726aal2, cost 1 codec_g726.so => (ITU G.726-32kbps G726 Transcoder) == Registered application 'StackPop' == Registered application 'Return' == Registered application 'GosubIf' == Registered application 'Gosub' app_stack.so => (Stack Routines) == Registered custom function SHA1 func_sha1.so => (SHA-1 computation dialplan function) == Registered application 'DISA' app_disa.so => (DISA (Direct Inward System Access) Application) == Registered application 'ControlPlayback' app_controlplayback.so => (Control Playback Application) == Registered application 'TestClient' == Registered application 'TestServer' app_test.so => (Interface Test Application) == Registered file format sln, extension(s) sln|raw format_sln.so => (Raw Signed Linear Audio support (SLN)) == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) format_jpeg.so => (JPEG (Joint Picture Experts Group) Image Format) == Registered application 'SendText' app_sendtext.so => (Send Text Applications) == Registered custom function DB == Registered custom function DB_EXISTS == Registered custom function DB_DELETE func_db.so => (Database (astdb) related dialplan functions) == Registered file format g729, extension(s) g729 format_g729.so => (Raw G729 data) == Registered application 'VoiceMail' == Registered application 'VoiceMailMain' == Registered application 'MailboxExists' == Registered application 'VMAuthenticate' == Parsing '/etc/asterisk/voicemail.conf': Found app_voicemail.so => (Comedian Mail (Voicemail System) with ODBC Storage) == Parsing '/etc/asterisk/amd.conf': Found -- AMD defaults: initialSilence [2500] greeting [1500] afterGreetingSilence [800] totalAnalysisTime [5000] minimumWordLength [100] betweenWordsSilence [50] maximumNumberOfWords [3] silenceThreshold [256] == Registered application 'AMD' app_amd.so => (Answering Machine Detection Application) == Registered application 'WaitForRing' app_waitforring.so => (Waits until first ring after time) == Registered custom function ENUMLOOKUP == Registered custom function TXTCIDNAME func_enum.so => (ENUM related dialplan functions) == Registered application 'Authenticate' app_authenticate.so => (Authentication Application) == Registered custom function GLOBAL func_global.so => (Global variable dialplan functions) == Registered application 'ICES' app_ices.so => (Encode and Stream via icecast and ices) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_alaw: using generic PLC [Mar 8 10:37:14] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'alawtolin' from format alaw to slin, cost 1 == Registered translator 'lintoalaw' from format slin to alaw, cost 1 codec_alaw.so => (A-law Coder/Decoder) == Registered application 'SetTransferCapability' app_settransfercapability.so => (Set ISDN Transfer Capability) res_clioriginate.so => (Call origination from the CLI) == Registered application 'Dial' == Registered application 'RetryDial' app_dial.so => (Dialing Application) res_convert.so => (File format conversion CLI command) == Registered translator 'ilbctolin' from format ilbc to slin, cost 6 == Registered translator 'lintoilbc' from format slin to ilbc, cost 42 codec_ilbc.so => (iLBC Coder/Decoder) == Parsing '/etc/asterisk/dundi.conf': Found == Parsing '/etc/asterisk/include/common/dundi.inc': Found [Mar 8 10:37:14] DEBUG[8805]: pbx_dundi.c:408 reset_global_eid: Seeding global EID '00:19:b9:af:ab:00' from 'eth0' [Mar 8 10:37:14] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key '00:0f:1f:69:4b:10' in family 'dundi/dpeers' [Mar 8 10:37:14] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key '00:11:43:5a:3e:f5' in family 'dundi/dpeers' [Mar 8 10:37:14] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key '00:11:43:ce:09:c2' in family 'dundi/dpeers' [Mar 8 10:37:14] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key '00:0f:1f:65:f8:a4' in family 'dundi/dpeers' == Using TOS bits 0 == DUNDi Ready and Listening on 0.0.0.0 port 4520 == Registered custom function DUNDILOOKUP pbx_dundi.so => (Distributed Universal Number Discovery (DUNDi)) == Parsing '/etc/asterisk/extensions.conf': Found == Parsing '/etc/asterisk/include/common/error_handler.inc': Found == Parsing '/etc/asterisk/include/common/settings.inc': Found == Parsing '/etc/asterisk/include/common/dialflags.inc': Found == Parsing '/etc/asterisk/include/common/access_control.inc': Found == Parsing '/etc/asterisk/include/common/feature_status.inc': Found == Parsing '/etc/asterisk/include/common/who.inc': Found == Parsing '/etc/asterisk/include/common/no_answer_handler.inc': Found == Parsing '/etc/asterisk/include/features/sub-huntgroups.inc': Found == Parsing '/etc/asterisk/include/routing/sub-route.inc': Found == Parsing '/etc/asterisk/include/features/sub-callerid.inc': Found == Parsing '/etc/asterisk/include/features/sub-queues.inc': Found == Parsing '/etc/asterisk/include/features/sub-cdr_rewriter.inc': Found == Parsing '/etc/asterisk/include/features/sub-callingcard.inc': Found == Parsing '/etc/asterisk/include/features/sub-callgroups.inc': Found == Parsing '/etc/asterisk/include/features/sub-maxcallcheck.inc': Found == Parsing '/etc/asterisk/include/features/sub-accountcodes.inc': Found == Parsing '/etc/asterisk/include/features/sub-callparking.inc': Found == Parsing '/etc/asterisk/include/features/sub-call_recording.inc': Found == Parsing '/etc/asterisk/include/features/sub-callforwarding.inc': Found == Parsing '/etc/asterisk/include/features/sub-findfollow.inc': Found == Parsing '/etc/asterisk/include/features/sub-dnd.inc': Found == Parsing '/etc/asterisk/include/features/sub-monitoring.inc': Found == Parsing '/etc/asterisk/include/features/standard_features.inc': Found == Parsing '/etc/asterisk/include/routing/termination.inc': Found == Parsing '/etc/asterisk/include/routing/origination.inc': Found == Parsing '/etc/asterisk/include/incs/hints.inc': Found == Setting global variable 'NPA' to '813' == Setting global variable 'DEFAULT_RING' to '24' == Setting global variable 'SERVER' to 'tampa3' == Setting global variable 'SS_CODE' to '3881' == Setting global variable 'SS_PREFIX' to '12786' == Setting global variable 'SS_FMFM' to 'telic102' == Setting global variable 'SUPPORT_NUMBER' to '18635830265' == Setting global variable 'SUPPORT_PBX' to 'telcel' == Setting global variable 'SUPPORT_VM' to '103@telcel&201@telcel' -- Registered extension context 'error_handler' -- Added extension 'null_val' priority 1 to error_handler -- Added extension 'null_val' priority 2 to error_handler -- Added extension 'null_val' priority 3 to error_handler -- Added extension 'null_val' priority 4 to error_handler -- Added extension 'null_val' priority 5 to error_handler -- Registered extension context 'pre-dialflags' -- Added extension 's' priority 1 to pre-dialflags -- Added extension 's' priority 2 to pre-dialflags -- Added extension 's' priority 3 to pre-dialflags -- Added extension 's' priority 4 to pre-dialflags -- Registered extension context 'sub-access_control' -- Added extension 's' priority 1 to sub-access_control -- Added extension 's' priority 2 to sub-access_control -- Added extension 's' priority 3 to sub-access_control -- Added extension 's' priority 4 to sub-access_control -- Added extension 's' priority 5 to sub-access_control -- Added extension 's' priority 6 to sub-access_control -- Added extension 's' priority 7 to sub-access_control -- Added extension 's' priority 8 to sub-access_control -- Added extension 's' priority 9 to sub-access_control -- Added extension 's' priority 10 to sub-access_control -- Added extension 's' priority 11 to sub-access_control -- Added extension 's' priority 12 to sub-access_control -- Added extension 'available' priority 1 to sub-access_control -- Added extension 'available' priority 2 to sub-access_control -- Added extension 'available' priority 3 to sub-access_control -- Added extension 'unavailable' priority 1 to sub-access_control -- Added extension 'unavailable' priority 2 to sub-access_control -- Added extension 'unavailable' priority 3 to sub-access_control -- Added extension 'unavailable' priority 4 to sub-access_control -- Added extension 'error' priority 1 to sub-access_control -- Added extension 'error' priority 2 to sub-access_control -- Registered extension context 'sub-feature_status' -- Added extension 's' priority 1 to sub-feature_status -- Added extension 's' priority 2 to sub-feature_status -- Added extension 's' priority 3 to sub-feature_status -- Added extension 's' priority 4 to sub-feature_status -- Added extension 's' priority 5 to sub-feature_status -- Added extension 's' priority 6 to sub-feature_status -- Added extension 's' priority 7 to sub-feature_status -- Registered extension context 'sub-who' -- Added extension 's' priority 1 to sub-who -- Added extension 's' priority 2 to sub-who -- Added extension 's' priority 3 to sub-who -- Added extension 's' priority 4 to sub-who -- Added extension 's' priority 5 to sub-who -- Added extension 's' priority 6 to sub-who -- Added extension 's' priority 7 to sub-who -- Added extension 's' priority 8 to sub-who -- Added extension 's' priority 9 to sub-who -- Added extension 's' priority 10 to sub-who -- Registered extension context 'sub-no_answer' -- Added extension 's' priority 1 to sub-no_answer -- Added extension 's' priority 2 to sub-no_answer -- Added extension 's' priority 3 to sub-no_answer -- Added extension 's' priority 4 to sub-no_answer -- Added extension 's' priority 5 to sub-no_answer -- Added extension 's' priority 6 to sub-no_answer -- Added extension 's' priority 7 to sub-no_answer -- Added extension 's' priority 8 to sub-no_answer -- Added extension 's' priority 9 to sub-no_answer -- Added extension 's' priority 10 to sub-no_answer -- Added extension 's' priority 11 to sub-no_answer -- Added extension 's' priority 12 to sub-no_answer -- Added extension 'no_dest' priority 1 to sub-no_answer -- Added extension 'no_dest' priority 2 to sub-no_answer -- Added extension 'no_dest' priority 3 to sub-no_answer -- Added extension 'no_dest' priority 4 to sub-no_answer -- Added extension 'aa' priority 1 to sub-no_answer -- Added extension 'aa' priority 2 to sub-no_answer -- Added extension 'aa' priority 3 to sub-no_answer -- Added extension 'vm' priority 1 to sub-no_answer -- Added extension 'vm' priority 2 to sub-no_answer -- Added extension 'vm' priority 3 to sub-no_answer -- Added extension 'vm' priority 4 to sub-no_answer -- Added extension 'vm' priority 5 to sub-no_answer -- Added extension 'vm' priority 6 to sub-no_answer -- Added extension 'vm' priority 7 to sub-no_answer -- Added extension 'oper' priority 1 to sub-no_answer -- Added extension 'oper' priority 2 to sub-no_answer -- Added extension 'oper' priority 3 to sub-no_answer -- Added extension 'oper' priority 4 to sub-no_answer -- Added extension 'oper' priority 5 to sub-no_answer -- Added extension 'oper' priority 6 to sub-no_answer -- Added extension 'oper' priority 7 to sub-no_answer -- Added extension 'oper' priority 8 to sub-no_answer -- Added extension 'user' priority 1 to sub-no_answer -- Added extension 'user' priority 2 to sub-no_answer -- Added extension 'user' priority 3 to sub-no_answer -- Added extension 'user' priority 4 to sub-no_answer -- Added extension 'user' priority 5 to sub-no_answer -- Registered extension context 'sub-huntgroup' -- Added extension '_X.' priority 1 to sub-huntgroup -- Added extension '_X.' priority 2 to sub-huntgroup -- Added extension '_X.' priority 3 to sub-huntgroup -- Added extension '_X.' priority 4 to sub-huntgroup -- Added extension '_X.' priority 5 to sub-huntgroup -- Added extension '_X.' priority 6 to sub-huntgroup -- Added extension '_X.' priority 7 to sub-huntgroup -- Added extension '_X.' priority 8 to sub-huntgroup -- Added extension '_X.' priority 9 to sub-huntgroup -- Added extension '_X.' priority 10 to sub-huntgroup -- Added extension '_X.' priority 11 to sub-huntgroup -- Added extension '_X.' priority 12 to sub-huntgroup -- Added extension '_X.' priority 13 to sub-huntgroup -- Added extension '_X.' priority 14 to sub-huntgroup -- Added extension '_X.' priority 15 to sub-huntgroup -- Added extension '_X.' priority 16 to sub-huntgroup -- Added extension '_X.' priority 17 to sub-huntgroup -- Added extension 'members' priority 1 to sub-huntgroup -- Added extension 'members' priority 2 to sub-huntgroup -- Added extension 'members' priority 3 to sub-huntgroup -- Added extension 'members' priority 4 to sub-huntgroup -- Added extension 'members' priority 5 to sub-huntgroup -- Added extension 'members' priority 6 to sub-huntgroup -- Added extension 'members' priority 7 to sub-huntgroup -- Added extension 'members' priority 8 to sub-huntgroup -- Added extension 'members' priority 9 to sub-huntgroup -- Added extension 'members' priority 10 to sub-huntgroup -- Added extension 'members' priority 11 to sub-huntgroup -- Added extension 'members' priority 12 to sub-huntgroup -- Added extension 'members' priority 13 to sub-huntgroup -- Added extension 'members' priority 14 to sub-huntgroup -- Added extension 'members' priority 15 to sub-huntgroup -- Added extension 'members' priority 16 to sub-huntgroup -- Added extension 'members' priority 17 to sub-huntgroup -- Added extension 'members' priority 18 to sub-huntgroup -- Added extension 'members' priority 19 to sub-huntgroup -- Added extension 'members' priority 20 to sub-huntgroup -- Added extension 'members' priority 21 to sub-huntgroup -- Added extension 'roundrobin' priority 1 to sub-huntgroup -- Added extension 'roundrobin' priority 2 to sub-huntgroup -- Added extension 'roundrobin' priority 3 to sub-huntgroup -- Added extension 'roundrobin' priority 4 to sub-huntgroup -- Added extension 'roundrobin' priority 5 to sub-huntgroup -- Added extension 'roundrobin' priority 6 to sub-huntgroup -- Added extension 'roundrobin' priority 7 to sub-huntgroup -- Added extension 'roundrobin' priority 8 to sub-huntgroup -- Added extension 'roundrobin' priority 9 to sub-huntgroup -- Added extension 'roundrobin' priority 10 to sub-huntgroup -- Added extension 'roundrobin' priority 11 to sub-huntgroup -- Added extension 'roundrobin' priority 12 to sub-huntgroup -- Added extension 'roundrobin' priority 13 to sub-huntgroup -- Added extension 'roundrobin' priority 14 to sub-huntgroup -- Added extension 'roundrobin' priority 15 to sub-huntgroup -- Added extension 'roundrobin' priority 16 to sub-huntgroup -- Added extension 'roundrobin' priority 17 to sub-huntgroup -- Added extension 'roundrobin' priority 18 to sub-huntgroup -- Added extension 'roundrobin' priority 19 to sub-huntgroup -- Added extension 'roundrobin' priority 20 to sub-huntgroup -- Added extension 'roundrobin' priority 21 to sub-huntgroup -- Added extension 'roundrobin' priority 22 to sub-huntgroup -- Added extension 'roundrobin' priority 23 to sub-huntgroup -- Added extension 'roundrobin' priority 24 to sub-huntgroup -- Added extension 'roundrobin' priority 25 to sub-huntgroup -- Added extension 'roundrobin' priority 26 to sub-huntgroup -- Added extension 'roundrobin' priority 27 to sub-huntgroup -- Added extension 'roundrobin' priority 28 to sub-huntgroup -- Added extension 'roundrobin' priority 29 to sub-huntgroup -- Added extension 'roundrobin' priority 30 to sub-huntgroup -- Added extension 'roundrobin' priority 31 to sub-huntgroup -- Added extension 'roundrobin' priority 32 to sub-huntgroup -- Added extension 'roundrobin' priority 33 to sub-huntgroup -- Added extension 'roundrobin' priority 34 to sub-huntgroup -- Added extension 'roundrobin' priority 35 to sub-huntgroup -- Added extension 'roundrobin' priority 36 to sub-huntgroup -- Added extension 'roundrobin' priority 37 to sub-huntgroup -- Added extension 'roundrobin' priority 38 to sub-huntgroup -- Added extension 'roundrobin' priority 39 to sub-huntgroup -- Added extension 'roundrobin' priority 40 to sub-huntgroup -- Added extension 'roundrobin' priority 41 to sub-huntgroup -- Added extension 'roundrobin' priority 42 to sub-huntgroup -- Added extension 'roundrobin' priority 43 to sub-huntgroup -- Added extension 'roundrobin' priority 44 to sub-huntgroup -- Added extension 'roundrobin' priority 45 to sub-huntgroup -- Added extension 'roundrobin' priority 46 to sub-huntgroup -- Added extension 'roundrobin' priority 47 to sub-huntgroup -- Added extension 'roundrobin' priority 48 to sub-huntgroup -- Added extension 'roundrobin' priority 49 to sub-huntgroup -- Added extension 'roundrobin' priority 50 to sub-huntgroup -- Added extension 'roundrobin' priority 51 to sub-huntgroup -- Added extension 'roundrobin' priority 52 to sub-huntgroup -- Added extension 'roundrobin' priority 53 to sub-huntgroup -- Added extension 'roundrobin' priority 54 to sub-huntgroup -- Added extension 'roundrobin' priority 55 to sub-huntgroup -- Added extension 'roundrobin' priority 56 to sub-huntgroup -- Added extension 'roundrobin' priority 57 to sub-huntgroup -- Added extension 'roundrobin' priority 58 to sub-huntgroup -- Added extension 'roundrobin' priority 59 to sub-huntgroup -- Added extension 'no_answer' priority 1 to sub-huntgroup -- Added extension 'no_answer' priority 2 to sub-huntgroup -- Added extension 'no_answer' priority 3 to sub-huntgroup -- Added extension 'no_answer' priority 4 to sub-huntgroup -- Added extension 'clean' priority 1 to sub-huntgroup -- Added extension 'clean' priority 2 to sub-huntgroup -- Added extension 'clean' priority 3 to sub-huntgroup -- Added extension 'clean' priority 4 to sub-huntgroup -- Added extension 'clean' priority 5 to sub-huntgroup -- Added extension 'clean' priority 6 to sub-huntgroup -- Added extension 'h' priority 1 to sub-huntgroup -- Added extension 'h' priority 2 to sub-huntgroup -- Added extension 'h' priority 3 to sub-huntgroup -- Added extension 'h' priority 4 to sub-huntgroup -- Registered extension context 'sub-route' -- Added extension 's' priority 1 to sub-route -- Added extension 's' priority 2 to sub-route -- Added extension 's' priority 3 to sub-route -- Added extension 's' priority 4 to sub-route -- Added extension 's' priority 5 to sub-route -- Added extension 's' priority 6 to sub-route -- Added extension 's' priority 7 to sub-route -- Added extension 's' priority 8 to sub-route -- Added extension 's' priority 9 to sub-route -- Added extension 's' priority 10 to sub-route -- Added extension 's' priority 11 to sub-route -- Added extension 's' priority 12 to sub-route -- Added extension 's' priority 13 to sub-route -- Added extension 's' priority 14 to sub-route -- Added extension 's' priority 15 to sub-route -- Added extension 's' priority 16 to sub-route -- Added extension 's' priority 17 to sub-route -- Added extension 's' priority 18 to sub-route -- Added extension 'device' priority 1 to sub-route -- Added extension 'device' priority 2 to sub-route -- Added extension 'device' priority 3 to sub-route -- Added extension 'device' priority 4 to sub-route -- Added extension 'device' priority 5 to sub-route -- Added extension 'conference' priority 1 to sub-route -- Added extension 'conference' priority 2 to sub-route -- Added extension 'conference' priority 3 to sub-route -- Added extension 'softswitch' priority 1 to sub-route -- Added extension 'softswitch' priority 2 to sub-route -- Added extension 'softswitch' priority 3 to sub-route -- Added extension 'aa' priority 1 to sub-route -- Added extension 'aa' priority 2 to sub-route -- Added extension 'aa' priority 3 to sub-route -- Added extension 'hg' priority 1 to sub-route -- Added extension 'hg' priority 2 to sub-route -- Added extension 'hg' priority 3 to sub-route -- Added extension 'hg' priority 4 to sub-route -- Added extension 'qq' priority 1 to sub-route -- Added extension 'qq' priority 2 to sub-route -- Added extension 'qq' priority 3 to sub-route -- Added extension 'qq' priority 4 to sub-route -- Added extension 'reject' priority 1 to sub-route -- Added extension 'reject' priority 2 to sub-route -- Added extension 'reject' priority 3 to sub-route -- Added extension 'reject' priority 4 to sub-route -- Added extension 'reject' priority 5 to sub-route -- Added extension 'reject' priority 6 to sub-route -- Added extension 'reject' priority 7 to sub-route -- Added extension 'reject' priority 8 to sub-route -- Added extension 'reject' priority 9 to sub-route -- Added extension 'reject' priority 10 to sub-route -- Added extension 'reject' priority 11 to sub-route -- Added extension 'h' priority 1 to sub-route -- Added extension 'h' priority 2 to sub-route -- Registered extension context 'sub-callerid' -- Added extension 's' priority 1 to sub-callerid -- Added extension 's' priority 2 to sub-callerid -- Added extension 's' priority 3 to sub-callerid -- Added extension 's' priority 4 to sub-callerid -- Added extension 's' priority 5 to sub-callerid -- Added extension 's' priority 6 to sub-callerid -- Added extension 's' priority 7 to sub-callerid -- Added extension 's' priority 8 to sub-callerid -- Added extension 's' priority 9 to sub-callerid -- Added extension 'in' priority 1 to sub-callerid -- Added extension 'in' priority 2 to sub-callerid -- Added extension 'in' priority 3 to sub-callerid -- Added extension 'in' priority 4 to sub-callerid -- Added extension 'in' priority 5 to sub-callerid -- Added extension 'in' priority 6 to sub-callerid -- Added extension 'in' priority 7 to sub-callerid -- Added extension 'in' priority 8 to sub-callerid -- Added extension 'in' priority 9 to sub-callerid -- Added extension 'in' priority 10 to sub-callerid -- Added extension 'in' priority 11 to sub-callerid -- Added extension 'in' priority 12 to sub-callerid -- Added extension 'in' priority 13 to sub-callerid -- Added extension 'in' priority 14 to sub-callerid -- Added extension 'out' priority 1 to sub-callerid -- Added extension 'out' priority 2 to sub-callerid -- Added extension 'out' priority 3 to sub-callerid -- Added extension 'out' priority 4 to sub-callerid -- Added extension 'out' priority 5 to sub-callerid -- Added extension 'out' priority 6 to sub-callerid -- Added extension 'out' priority 7 to sub-callerid -- Added extension 'out' priority 8 to sub-callerid -- Added extension 'out' priority 9 to sub-callerid -- Added extension 'out' priority 10 to sub-callerid -- Added extension 'out' priority 11 to sub-callerid -- Added extension 'out' priority 12 to sub-callerid -- Added extension 'out' priority 13 to sub-callerid -- Added extension 'out' priority 14 to sub-callerid -- Added extension 'out' priority 15 to sub-callerid -- Added extension 'out' priority 16 to sub-callerid -- Added extension 'out' priority 17 to sub-callerid -- Added extension 'out' priority 18 to sub-callerid -- Added extension 'out' priority 19 to sub-callerid -- Added extension 'out' priority 20 to sub-callerid -- Added extension 'out' priority 21 to sub-callerid -- Added extension 'out' priority 22 to sub-callerid -- Added extension 'out' priority 23 to sub-callerid -- Added extension 'out' priority 24 to sub-callerid -- Added extension 'out' priority 25 to sub-callerid -- Added extension 'out' priority 26 to sub-callerid -- Added extension 'out' priority 27 to sub-callerid -- Added extension 'out' priority 28 to sub-callerid -- Added extension 'out' priority 29 to sub-callerid -- Added extension 'out' priority 30 to sub-callerid -- Added extension 'out' priority 31 to sub-callerid -- Added extension 'out' priority 32 to sub-callerid -- Added extension 'out' priority 33 to sub-callerid -- Added extension 'out(inside)' priority 34 to sub-callerid -- Added extension 'out' priority 35 to sub-callerid -- Added extension 'out' priority 36 to sub-callerid -- Added extension 'out' priority 37 to sub-callerid -- Added extension 'out' priority 38 to sub-callerid -- Added extension 'nocallid' priority 1 to sub-callerid -- Added extension 'nocallid' priority 2 to sub-callerid -- Added extension 'nocallid' priority 3 to sub-callerid -- Added extension 'nocallid' priority 4 to sub-callerid -- Added extension 'nocallid' priority 5 to sub-callerid -- Added extension 'nocallid' priority 6 to sub-callerid -- Added extension 'nocallid' priority 7 to sub-callerid -- Added extension 'nocallid' priority 8 to sub-callerid -- Added extension 'lenchk' priority 1 to sub-callerid -- Added extension 'lenchk' priority 2 to sub-callerid -- Added extension 'lenchk' priority 3 to sub-callerid -- Added extension 'lenchk' priority 4 to sub-callerid -- Added extension 'lenchk' priority 5 to sub-callerid -- Added extension 'lenchk' priority 6 to sub-callerid -- Added extension 'lenchk' priority 7 to sub-callerid -- Added extension 'rewrite' priority 1 to sub-callerid -- Added extension 'rewrite' priority 2 to sub-callerid -- Added extension 'rewrite' priority 3 to sub-callerid -- Added extension 'rewrite' priority 4 to sub-callerid -- Added extension 'rewrite' priority 5 to sub-callerid -- Added extension 'rewrite' priority 6 to sub-callerid -- Added extension 'rewrite' priority 7 to sub-callerid -- Added extension 'rewrite' priority 8 to sub-callerid -- Added extension 'rewrite' priority 9 to sub-callerid -- Added extension 'rewrite' priority 10 to sub-callerid -- Added extension 'rewrite' priority 11 to sub-callerid -- Added extension 'rewrite' priority 12 to sub-callerid -- Added extension 'block' priority 1 to sub-callerid -- Added extension 'block' priority 2 to sub-callerid -- Added extension 'block' priority 3 to sub-callerid -- Added extension 'block' priority 4 to sub-callerid -- Added extension 'block' priority 5 to sub-callerid -- Added extension 'block' priority 6 to sub-callerid -- Registered extension context 'sub-queues' -- Added extension '_X.' priority 1 to sub-queues -- Added extension '_X.' priority 2 to sub-queues -- Added extension '_X.' priority 3 to sub-queues -- Added extension '_X.' priority 4 to sub-queues -- Added extension '_X.' priority 5 to sub-queues -- Added extension '_X.' priority 6 to sub-queues -- Added extension '_X.' priority 7 to sub-queues -- Added extension '_X.' priority 8 to sub-queues -- Added extension '_X.' priority 9 to sub-queues -- Registered extension context 'q-member' -- Added extension '_0-.' priority 1 to q-member -- Added extension '_0-.' priority 2 to q-member -- Registered extension context 'q-control' [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 18 -- Added extension '_.' priority 1 to q-control [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 20 -- Added extension '_.' priority 2 to q-control [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 21 -- Added extension '_.' priority 3 to q-control [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 22 -- Added extension '_.' priority 4 to q-control [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 23 -- Added extension '_.' priority 5 to q-control [Mar 8 10:37:14] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 24 -- Added extension '_.' priority 6 to q-control -- Added extension 'remote_lookup' priority 1 to q-control -- Added extension 'remote_lookup' priority 2 to q-control -- Added extension 'remote_lookup' priority 3 to q-control -- Added extension 'remote_lookup' priority 4 to q-control -- Added extension 'no_member_found' priority 1 to q-control -- Added extension 'no_member_found' priority 2 to q-control -- Added extension 'h' priority 1 to q-control -- Registered extension context 'sub-cdr_rewriter' -- Added extension '_X.' priority 1 to sub-cdr_rewriter -- Added extension '_X.' priority 2 to sub-cdr_rewriter -- Added extension '_X.' priority 3 to sub-cdr_rewriter -- Added extension '_X.' priority 4 to sub-cdr_rewriter -- Added extension '_X.' priority 5 to sub-cdr_rewriter -- Added extension '_X.' priority 6 to sub-cdr_rewriter -- Added extension '_X.' priority 7 to sub-cdr_rewriter -- Added extension '_X.' priority 8 to sub-cdr_rewriter -- Added extension '_X.' priority 9 to sub-cdr_rewriter -- Added extension '_X.' priority 10 to sub-cdr_rewriter -- Added extension '_X.' priority 11 to sub-cdr_rewriter -- Added extension '_X.' priority 12 to sub-cdr_rewriter -- Added extension '_X.' priority 13 to sub-cdr_rewriter -- Added extension 'h' priority 1 to sub-cdr_rewriter -- Added extension 'h' priority 2 to sub-cdr_rewriter -- Added extension 'h' priority 3 to sub-cdr_rewriter -- Added extension 'h' priority 4 to sub-cdr_rewriter -- Added extension 'h' priority 5 to sub-cdr_rewriter -- Registered extension context 'sub-callingcard' -- Added extension '_X.' priority 1 to sub-callingcard -- Added extension '_X.' priority 2 to sub-callingcard -- Added extension '_X.' priority 3 to sub-callingcard -- Added extension '_X.' priority 4 to sub-callingcard -- Added extension '_X.' priority 5 to sub-callingcard -- Added extension '_X.' priority 6 to sub-callingcard -- Added extension '_X.' priority 7 to sub-callingcard -- Added extension '_X.' priority 8 to sub-callingcard -- Added extension '_X.' priority 9 to sub-callingcard -- Added extension '_X.' priority 10 to sub-callingcard -- Added extension '_X.' priority 11 to sub-callingcard -- Added extension '_X.' priority 12 to sub-callingcard -- Added extension '_X.' priority 13 to sub-callingcard -- Added extension '_X.' priority 14 to sub-callingcard -- Added extension '_X.' priority 15 to sub-callingcard -- Added extension '_X.' priority 16 to sub-callingcard -- Added extension '_X.' priority 17 to sub-callingcard -- Added extension '_X.' priority 18 to sub-callingcard -- Added extension 'pin' priority 1 to sub-callingcard -- Added extension 'pin' priority 2 to sub-callingcard -- Added extension 'pin' priority 3 to sub-callingcard -- Added extension 'pin' priority 4 to sub-callingcard -- Added extension 'pin' priority 5 to sub-callingcard -- Added extension 'pin' priority 6 to sub-callingcard -- Added extension 'pin' priority 7 to sub-callingcard -- Added extension 'pin' priority 8 to sub-callingcard -- Added extension 'auth_failure' priority 1 to sub-callingcard -- Added extension 'auth_failure' priority 2 to sub-callingcard -- Added extension 'auth_failure' priority 3 to sub-callingcard -- Added extension 'auth_failure' priority 4 to sub-callingcard -- Added extension 'not_enough_data' priority 1 to sub-callingcard -- Added extension 'not_enough_data' priority 2 to sub-callingcard -- Added extension 'not_enough_data' priority 3 to sub-callingcard -- Added extension 'not_enough_data' priority 4 to sub-callingcard -- Added extension 'not_enough_data' priority 5 to sub-callingcard -- Added extension 'get_num' priority 1 to sub-callingcard -- Added extension 'get_num' priority 2 to sub-callingcard -- Added extension 'get_num' priority 3 to sub-callingcard -- Added extension 'get_num' priority 4 to sub-callingcard -- Added extension 'get_num' priority 5 to sub-callingcard -- Added extension 'get_num' priority 6 to sub-callingcard -- Added extension 'get_num' priority 7 to sub-callingcard -- Registered extension context 'sub-cc_usercode' -- Added extension 's' priority 1 to sub-cc_usercode -- Added extension 's' priority 2 to sub-cc_usercode -- Added extension 's' priority 3 to sub-cc_usercode -- Added extension 's' priority 4 to sub-cc_usercode -- Added extension 's' priority 5 to sub-cc_usercode -- Added extension 's' priority 6 to sub-cc_usercode -- Added extension 's' priority 7 to sub-cc_usercode -- Added extension 's' priority 8 to sub-cc_usercode -- Added extension 's' priority 9 to sub-cc_usercode -- Added extension 's' priority 10 to sub-cc_usercode -- Registered extension context 'sub-callgroups' -- Added extension 's' priority 1 to sub-callgroups -- Added extension 's' priority 2 to sub-callgroups -- Added extension 's' priority 3 to sub-callgroups -- Added extension 's' priority 4 to sub-callgroups -- Added extension 's' priority 5 to sub-callgroups -- Added extension 's' priority 6 to sub-callgroups -- Added extension 's' priority 7 to sub-callgroups -- Added extension 'dev' priority 1 to sub-callgroups -- Added extension 'dev' priority 2 to sub-callgroups -- Added extension 'dev' priority 3 to sub-callgroups -- Added extension 'dev' priority 4 to sub-callgroups -- Added extension 'dev' priority 5 to sub-callgroups -- Added extension 'dev' priority 6 to sub-callgroups -- Added extension 'dev' priority 7 to sub-callgroups -- Added extension 'dev' priority 8 to sub-callgroups -- Added extension 'did' priority 1 to sub-callgroups -- Added extension 'did' priority 2 to sub-callgroups -- Added extension 'did' priority 3 to sub-callgroups -- Added extension 'did' priority 4 to sub-callgroups -- Added extension 'did' priority 5 to sub-callgroups -- Added extension 'did' priority 6 to sub-callgroups -- Added extension 'did' priority 7 to sub-callgroups -- Added extension 'did' priority 8 to sub-callgroups -- Added extension 'chk' priority 1 to sub-callgroups -- Added extension 'chk' priority 2 to sub-callgroups -- Added extension 'chk' priority 3 to sub-callgroups -- Added extension 'chk' priority 4 to sub-callgroups -- Added extension 'chk' priority 5 to sub-callgroups -- Added extension 'block' priority 1 to sub-callgroups -- Added extension 'block' priority 2 to sub-callgroups -- Added extension 'block' priority 3 to sub-callgroups -- Added extension 'block' priority 4 to sub-callgroups -- Added extension 'block' priority 5 to sub-callgroups -- Added extension 'return' priority 1 to sub-callgroups -- Added extension 'return' priority 2 to sub-callgroups -- Registered extension context 'sub-maxcallcheck' -- Added extension 's' priority 1 to sub-maxcallcheck -- Added extension 's' priority 2 to sub-maxcallcheck -- Added extension 's' priority 3 to sub-maxcallcheck -- Added extension 's' priority 4 to sub-maxcallcheck -- Added extension 's' priority 5 to sub-maxcallcheck -- Added extension 's' priority 6 to sub-maxcallcheck -- Added extension 's' priority 7 to sub-maxcallcheck -- Added extension 's' priority 8 to sub-maxcallcheck -- Added extension 's' priority 9 to sub-maxcallcheck -- Added extension 's' priority 10 to sub-maxcallcheck -- Added extension 's' priority 11 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 1 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 2 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 3 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 4 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 5 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 6 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 7 to sub-maxcallcheck -- Added extension 'too_many_trunks' priority 8 to sub-maxcallcheck -- Registered extension context 'sub-accountcodes' -- Added extension 's' priority 1 to sub-accountcodes -- Added extension 's' priority 2 to sub-accountcodes -- Added extension 's' priority 3 to sub-accountcodes -- Added extension 's' priority 4 to sub-accountcodes -- Added extension 's' priority 5 to sub-accountcodes -- Added extension 's' priority 6 to sub-accountcodes -- Added extension 's' priority 7 to sub-accountcodes -- Added extension 's' priority 8 to sub-accountcodes -- Added extension 's' priority 9 to sub-accountcodes -- Added extension 's' priority 10 to sub-accountcodes -- Added extension 's' priority 11 to sub-accountcodes -- Added extension 's' priority 12 to sub-accountcodes -- Added extension 's' priority 13 to sub-accountcodes -- Added extension 's' priority 14 to sub-accountcodes -- Added extension 's' priority 15 to sub-accountcodes -- Added extension 's' priority 16 to sub-accountcodes -- Added extension 's' priority 17 to sub-accountcodes -- Added extension 's' priority 18 to sub-accountcodes -- Added extension 's' priority 19 to sub-accountcodes -- Added extension 's' priority 20 to sub-accountcodes -- Added extension 's' priority 21 to sub-accountcodes -- Added extension 'bad_pin' priority 1 to sub-accountcodes -- Added extension 'bad_pin' priority 2 to sub-accountcodes -- Added extension 'bad_pin' priority 3 to sub-accountcodes -- Added extension 'bad_pin' priority 4 to sub-accountcodes -- Added extension 'bad_pin' priority 5 to sub-accountcodes -- Registered extension context 'sub-callparking' -- Added extension 's' priority 1 to sub-callparking -- Added extension 's' priority 2 to sub-callparking -- Added extension 's' priority 3 to sub-callparking -- Added extension 's' priority 4 to sub-callparking -- Added extension 's' priority 5 to sub-callparking -- Added extension 's' priority 6 to sub-callparking -- Added extension 's' priority 7 to sub-callparking -- Added extension 's' priority 8 to sub-callparking -- Added extension 's' priority 9 to sub-callparking -- Added extension 's' priority 10 to sub-callparking -- Added extension 's' priority 11 to sub-callparking -- Added extension 's' priority 12 to sub-callparking -- Added extension 's' priority 13 to sub-callparking -- Added extension 's' priority 14 to sub-callparking -- Added extension 's' priority 15 to sub-callparking -- Added extension 's' priority 16 to sub-callparking -- Added extension 's' priority 17 to sub-callparking -- Added extension 's' priority 18 to sub-callparking -- Added extension 'error' priority 1 to sub-callparking -- Added extension 'error' priority 2 to sub-callparking -- Added extension 'error' priority 3 to sub-callparking -- Added extension 'error' priority 4 to sub-callparking -- Added extension 'h' priority 1 to sub-callparking -- Added extension 'h' priority 2 to sub-callparking -- Added extension 'h' priority 3 to sub-callparking -- Registered extension context 'parking' -- Added extension 's' priority 1 to parking -- Added extension 's' priority 2 to parking -- Added extension 's' priority 3 to parking -- Added extension 's' priority 4 to parking -- Added extension 'h' priority 1 to parking -- Added extension 'h' priority 2 to parking -- Added extension 'h' priority 3 to parking -- Added extension 'h' priority 4 to parking -- Added extension 'h' priority 5 to parking -- Registered extension context 'sub-call_recording' -- Added extension 's' priority 1 to sub-call_recording -- Added extension 's' priority 2 to sub-call_recording -- Added extension 's' priority 3 to sub-call_recording -- Added extension 's' priority 4 to sub-call_recording -- Added extension 's' priority 5 to sub-call_recording -- Added extension 's' priority 6 to sub-call_recording -- Added extension 's' priority 7 to sub-call_recording -- Added extension 's' priority 8 to sub-call_recording -- Added extension 's' priority 9 to sub-call_recording -- Added extension 's' priority 10 to sub-call_recording -- Added extension 's' priority 11 to sub-call_recording -- Added extension 's' priority 12 to sub-call_recording -- Added extension 's' priority 13 to sub-call_recording -- Added extension 's' priority 14 to sub-call_recording -- Added extension 's' priority 15 to sub-call_recording -- Added extension 's' priority 16 to sub-call_recording -- Added extension 's' priority 17 to sub-call_recording -- Added extension 's' priority 18 to sub-call_recording -- Added extension 's' priority 19 to sub-call_recording -- Registered extension context 'sub-callforwarding' -- Added extension 's' priority 1 to sub-callforwarding -- Added extension 's' priority 2 to sub-callforwarding -- Added extension 's' priority 3 to sub-callforwarding -- Added extension 's' priority 4 to sub-callforwarding -- Added extension 's' priority 5 to sub-callforwarding -- Added extension 's' priority 6 to sub-callforwarding -- Added extension 's' priority 7 to sub-callforwarding -- Added extension 's' priority 8 to sub-callforwarding -- Added extension 's' priority 9 to sub-callforwarding -- Added extension 's' priority 10 to sub-callforwarding -- Added extension 's' priority 11 to sub-callforwarding -- Added extension 's' priority 12 to sub-callforwarding -- Added extension 's' priority 13 to sub-callforwarding -- Added extension 's' priority 14 to sub-callforwarding -- Added extension 's' priority 15 to sub-callforwarding -- Added extension 's' priority 16 to sub-callforwarding -- Added extension 's' priority 17 to sub-callforwarding -- Added extension 'pre' priority 1 to sub-callforwarding -- Added extension 'pre' priority 2 to sub-callforwarding -- Added extension 'pre' priority 3 to sub-callforwarding -- Added extension 'pre' priority 4 to sub-callforwarding -- Added extension 'pre' priority 5 to sub-callforwarding -- Added extension 'pre' priority 6 to sub-callforwarding -- Added extension 'post' priority 1 to sub-callforwarding -- Added extension 'post' priority 2 to sub-callforwarding -- Added extension 'post' priority 3 to sub-callforwarding -- Added extension 'post' priority 4 to sub-callforwarding -- Added extension 'post' priority 5 to sub-callforwarding -- Added extension 'post' priority 6 to sub-callforwarding -- Added extension 'post' priority 7 to sub-callforwarding -- Added extension 'dial_fwd' priority 1 to sub-callforwarding -- Added extension 'dial_fwd' priority 2 to sub-callforwarding -- Added extension 'dial_fwd' priority 3 to sub-callforwarding -- Added extension 'dial_fwd' priority 4 to sub-callforwarding -- Added extension 'dial_fwd' priority 5 to sub-callforwarding -- Added extension 'dial_fwd' priority 6 to sub-callforwarding -- Added extension 'dial_fwd' priority 7 to sub-callforwarding -- Added extension 'dial_fwd' priority 8 to sub-callforwarding -- Added extension 'dial_fwd' priority 9 to sub-callforwarding -- Added extension 'dial_fwd' priority 10 to sub-callforwarding -- Added extension 'dial_fwd' priority 11 to sub-callforwarding -- Added extension 'dial_fwd' priority 12 to sub-callforwarding -- Added extension 'dial_fwd' priority 13 to sub-callforwarding -- Added extension 'dial_fwd' priority 14 to sub-callforwarding -- Added extension 'dial_fwd' priority 15 to sub-callforwarding -- Added extension 'dial_fwd' priority 16 to sub-callforwarding -- Added extension 'dial_fwd' priority 17 to sub-callforwarding -- Added extension 'dial_fwd' priority 18 to sub-callforwarding -- Added extension '1' priority 1 to sub-callforwarding -- Added extension '1' priority 2 to sub-callforwarding -- Added extension '1' priority 3 to sub-callforwarding -- Registered extension context 'sub-findfollow' -- Added extension 's' priority 1 to sub-findfollow -- Added extension 's' priority 2 to sub-findfollow -- Added extension 's' priority 3 to sub-findfollow -- Added extension 's' priority 4 to sub-findfollow -- Added extension 's' priority 5 to sub-findfollow -- Added extension 's' priority 6 to sub-findfollow -- Added extension 's' priority 7 to sub-findfollow -- Added extension 's' priority 8 to sub-findfollow -- Added extension 's' priority 9 to sub-findfollow -- Added extension 's' priority 10 to sub-findfollow -- Added extension 's' priority 11 to sub-findfollow -- Added extension 's' priority 12 to sub-findfollow -- Added extension 's' priority 13 to sub-findfollow -- Added extension 's' priority 14 to sub-findfollow -- Added extension 's' priority 15 to sub-findfollow -- Added extension 's' priority 16 to sub-findfollow -- Added extension 's' priority 17 to sub-findfollow -- Added extension 's' priority 18 to sub-findfollow -- Added extension 's' priority 19 to sub-findfollow -- Registered extension context 'flex_findfollow' -- Added extension '_X.' priority 1 to flex_findfollow -- Added extension '_X.' priority 2 to flex_findfollow -- Added extension '_X.' priority 3 to flex_findfollow -- Added extension '_X.' priority 4 to flex_findfollow -- Added extension '_X.' priority 5 to flex_findfollow -- Added extension '_X.' priority 6 to flex_findfollow -- Added extension '_X.' priority 7 to flex_findfollow -- Added extension '_X.' priority 8 to flex_findfollow -- Added extension '_X.' priority 9 to flex_findfollow -- Added extension '_X.' priority 10 to flex_findfollow -- Added extension '_X.' priority 11 to flex_findfollow -- Added extension '_X.' priority 12 to flex_findfollow -- Added extension '_X.' priority 13 to flex_findfollow -- Added extension '_X.' priority 14 to flex_findfollow -- Added extension '_X.' priority 15 to flex_findfollow -- Added extension '_X.' priority 16 to flex_findfollow -- Added extension '_X.' priority 17 to flex_findfollow -- Added extension '_X.' priority 18 to flex_findfollow -- Added extension '_X.' priority 19 to flex_findfollow -- Registered extension context 'sub-dnd' -- Added extension 's' priority 1 to sub-dnd -- Added extension 's' priority 2 to sub-dnd -- Added extension 's' priority 3 to sub-dnd -- Added extension 's' priority 4 to sub-dnd -- Added extension 's' priority 5 to sub-dnd -- Added extension 's' priority 6 to sub-dnd -- Added extension 's' priority 7 to sub-dnd -- Added extension 's' priority 8 to sub-dnd -- Added extension 's' priority 9 to sub-dnd -- Added extension 's' priority 10 to sub-dnd -- Registered extension context 'sub-monitoring' -- Added extension 's' priority 1 to sub-monitoring -- Added extension 's' priority 2 to sub-monitoring -- Added extension 's' priority 3 to sub-monitoring -- Added extension 's' priority 4 to sub-monitoring -- Added extension 's' priority 5 to sub-monitoring -- Added extension 's' priority 6 to sub-monitoring -- Added extension 's' priority 7 to sub-monitoring -- Added extension 's' priority 8 to sub-monitoring -- Added extension 's' priority 9 to sub-monitoring -- Added extension 's' priority 10 to sub-monitoring -- Added extension 's' priority 11 to sub-monitoring -- Registered extension context 'standard_features' -- Including context 'dnd' in context 'standard_features' -- Including context 'call_forwarding' in context 'standard_features' -- Including context 'personal_conference' in context 'standard_features' -- Including context 'cid_block' in context 'standard_features' -- Including context 'alarm_clock' in context 'standard_features' -- Including context 'say_last_call' in context 'standard_features' -- Including context 'last_call_return' in context 'standard_features' -- Including context 'last_number_redial' in context 'standard_features' -- Including context 'voicemail' in context 'standard_features' -- Including context 'e911_verify' in context 'standard_features' -- Including context 'monitoring' in context 'standard_features' -- Including context 'paging' in context 'standard_features' -- Including context 'speed_dial' in context 'standard_features' -- Including context 'call_parking' in context 'standard_features' -- Including context 'test' in context 'standard_features' -- Registered extension context 'dnd' -- Added extension '*3631' priority 1 to dnd -- Added extension '*3631' priority 2 to dnd -- Added extension '*3631' priority 3 to dnd -- Added extension '*3631' priority 4 to dnd -- Added extension '*3631' priority 5 to dnd -- Added extension '*3631' priority 6 to dnd -- Added extension '*3631' priority 7 to dnd -- Added extension '*3630' priority 1 to dnd -- Added extension '*3630' priority 2 to dnd -- Added extension '*3630' priority 3 to dnd -- Added extension '*3630' priority 4 to dnd -- Added extension '*3630' priority 5 to dnd -- Added extension '*3630' priority 6 to dnd -- Added extension '*3630' priority 7 to dnd -- Registered extension context 'call_forwarding' -- Added extension '_*3931NXXNXXXXXX' priority 1 to call_forwarding -- Added extension '_*3931NXXNXXXXXX' priority 2 to call_forwarding -- Added extension '_*3931NXXNXXXXXX' priority 3 to call_forwarding -- Added extension '_*3931NXXNXXXXXX' priority 4 to call_forwarding -- Added extension '*3931' priority 1 to call_forwarding -- Added extension '*3931' priority 2 to call_forwarding -- Added extension '*3931' priority 3 to call_forwarding -- Added extension '*3931' priority 4 to call_forwarding -- Added extension '*3931' priority 5 to call_forwarding -- Added extension '*3931' priority 6 to call_forwarding -- Added extension '*3931' priority 7 to call_forwarding -- Added extension '*3931' priority 8 to call_forwarding -- Added extension '*3931' priority 9 to call_forwarding -- Added extension '*3931' priority 10 to call_forwarding -- Added extension '*3931' priority 11 to call_forwarding -- Added extension '*3931' priority 12 to call_forwarding -- Added extension '*3931' priority 13 to call_forwarding -- Added extension '*3931' priority 14 to call_forwarding -- Added extension '*3931' priority 15 to call_forwarding -- Added extension '*3931' priority 16 to call_forwarding -- Added extension '*3931' priority 17 to call_forwarding -- Added extension '*3931' priority 18 to call_forwarding -- Added extension '*3931' priority 19 to call_forwarding -- Added extension '*3931' priority 20 to call_forwarding -- Added extension '*3931' priority 21 to call_forwarding -- Added extension '*3931' priority 22 to call_forwarding -- Added extension '*3931' priority 23 to call_forwarding -- Added extension '*3931' priority 24 to call_forwarding -- Added extension '*3931' priority 25 to call_forwarding -- Added extension '*3931' priority 26 to call_forwarding -- Added extension '*3931' priority 27 to call_forwarding -- Added extension '*3931' priority 28 to call_forwarding -- Added extension '*3931' priority 29 to call_forwarding -- Added extension '*3931' priority 30 to call_forwarding -- Added extension '*3931' priority 31 to call_forwarding -- Added extension '*3931' priority 32 to call_forwarding -- Added extension '*3931' priority 33 to call_forwarding -- Added extension '*3931' priority 34 to call_forwarding -- Added extension '*3931' priority 35 to call_forwarding -- Added extension '*3931' priority 36 to call_forwarding -- Added extension '*3931' priority 37 to call_forwarding -- Added extension '*3931' priority 38 to call_forwarding -- Added extension '*3931' priority 39 to call_forwarding -- Added extension '*3931' priority 40 to call_forwarding -- Added extension '*3931' priority 41 to call_forwarding -- Added extension '*3931' priority 42 to call_forwarding -- Added extension '*3931' priority 43 to call_forwarding -- Added extension '*3931' priority 44 to call_forwarding -- Added extension '*3930' priority 1 to call_forwarding -- Added extension '*3930' priority 2 to call_forwarding -- Added extension '*3930' priority 3 to call_forwarding -- Added extension '*3930' priority 4 to call_forwarding -- Added extension '*3930' priority 5 to call_forwarding -- Added extension '*3930' priority 6 to call_forwarding -- Added extension '*3930' priority 7 to call_forwarding -- Registered extension context 'personal_conference' -- Added extension '*200' priority 1 to personal_conference -- Added extension '*200' priority 2 to personal_conference -- Added extension '*200' priority 3 to personal_conference -- Added extension '*200' priority 4 to personal_conference -- Added extension '*200' priority 5 to personal_conference -- Added extension '*200' priority 6 to personal_conference -- Added extension '*200' priority 7 to personal_conference -- Added extension '*200' priority 8 to personal_conference -- Added extension '*200' priority 9 to personal_conference -- Added extension '*200' priority 10 to personal_conference -- Added extension '*200' priority 11 to personal_conference -- Added extension '*200' priority 12 to personal_conference -- Added extension '*200' priority 13 to personal_conference -- Added extension '*200' priority 14 to personal_conference -- Added extension '*200' priority 15 to personal_conference -- Added extension '_*200.' priority 1 to personal_conference -- Added extension '_*200.' priority 2 to personal_conference -- Added extension '_*200.' priority 3 to personal_conference -- Added extension '_*200.' priority 4 to personal_conference -- Added extension '_*200.' priority 5 to personal_conference -- Added extension '_*200.' priority 6 to personal_conference -- Added extension '_*200.' priority 7 to personal_conference -- Added extension '_*200.' priority 8 to personal_conference -- Added extension '_*200.' priority 9 to personal_conference -- Added extension '_*200.' priority 10 to personal_conference -- Added extension '_*200.' priority 11 to personal_conference -- Added extension '_*200.' priority 12 to personal_conference -- Added extension '_*200.' priority 13 to personal_conference -- Added extension '_*200.' priority 14 to personal_conference -- Registered extension context 'last_number_redial' -- Added extension '*55' priority 1 to last_number_redial -- Added extension '*55' priority 2 to last_number_redial -- Added extension '*55' priority 3 to last_number_redial -- Added extension '*55' priority 4 to last_number_redial -- Added extension '*55' priority 5 to last_number_redial -- Added extension '*55' priority 6 to last_number_redial -- Added extension '*55' priority 7 to last_number_redial -- Added extension '*55' priority 8 to last_number_redial -- Added extension '*55' priority 9 to last_number_redial -- Added extension '*55' priority 10 to last_number_redial -- Registered extension context 'last_call_return' -- Added extension '*67' priority 1 to last_call_return -- Added extension '*67' priority 2 to last_call_return -- Added extension '*67' priority 3 to last_call_return -- Added extension '*67' priority 4 to last_call_return -- Added extension '*67' priority 5 to last_call_return -- Added extension '*67' priority 6 to last_call_return -- Added extension '*67' priority 7 to last_call_return -- Added extension '*67' priority 8 to last_call_return -- Added extension '*67' priority 9 to last_call_return -- Added extension '*67' priority 10 to last_call_return -- Registered extension context 'say_last_call' -- Added extension '*69' priority 1 to say_last_call -- Added extension '*69' priority 2 to say_last_call -- Added extension '*69' priority 3 to say_last_call -- Added extension '*69' priority 4 to say_last_call -- Added extension '*69' priority 5 to say_last_call -- Added extension '*69' priority 6 to say_last_call -- Added extension '*69' priority 7 to say_last_call -- Added extension '*69' priority 8 to say_last_call -- Added extension '*69' priority 9 to say_last_call -- Added extension '*69' priority 10 to say_last_call -- Added extension '*69' priority 11 to say_last_call -- Added extension '*69' priority 12 to say_last_call -- Added extension '*69' priority 13 to say_last_call -- Added extension '*69' priority 14 to say_last_call -- Added extension '*69' priority 15 to say_last_call -- Added extension '*69' priority 16 to say_last_call -- Registered extension context 'cid_block' -- Added extension '*33' priority 1 to cid_block -- Added extension '*33' priority 2 to cid_block -- Added extension '*33' priority 3 to cid_block -- Added extension '*33' priority 4 to cid_block -- Added extension '*33' priority 5 to cid_block -- Added extension '*33' priority 6 to cid_block -- Added extension '*33' priority 7 to cid_block -- Added extension '*44' priority 1 to cid_block -- Added extension '*44' priority 2 to cid_block -- Added extension '*44' priority 3 to cid_block -- Added extension '*44' priority 4 to cid_block -- Added extension '*44' priority 5 to cid_block -- Added extension '*44' priority 6 to cid_block -- Added extension '*44' priority 7 to cid_block -- Added extension '*44' priority 8 to cid_block -- Registered extension context 'voicemail' -- Added extension 'vm' priority 1 to voicemail -- Added extension 'vm' priority 2 to voicemail -- Added extension 'vm' priority 3 to voicemail -- Added extension 'vm' priority 4 to voicemail -- Added extension 'vm' priority 5 to voicemail -- Added extension 'vm' priority 6 to voicemail -- Added extension 'vm' priority 7 to voicemail -- Added extension 'vm' priority 8 to voicemail -- Added extension 'vm' priority 9 to voicemail -- Added extension 'vm' priority 10 to voicemail -- Added extension '*8500' priority 1 to voicemail -- Added extension '*100' priority 1 to voicemail -- Registered extension context 'e911_verify' -- Added extension '*8080911' priority 1 to e911_verify -- Added extension '*8080911' priority 2 to e911_verify -- Added extension '*8080911' priority 3 to e911_verify -- Added extension '*8080911' priority 4 to e911_verify -- Added extension '*8080911' priority 5 to e911_verify -- Registered extension context 'alarm_clock' -- Added extension '*77' priority 1 to alarm_clock -- Added extension '*77' priority 2 to alarm_clock -- Added extension '*77' priority 3 to alarm_clock -- Added extension '*77' priority 4 to alarm_clock -- Added extension '*77' priority 5 to alarm_clock -- Added extension '*77' priority 6 to alarm_clock -- Added extension '*77' priority 7 to alarm_clock -- Added extension '*77' priority 8 to alarm_clock -- Added extension '*77' priority 9 to alarm_clock -- Added extension '*77' priority 10 to alarm_clock -- Added extension '*77' priority 11 to alarm_clock -- Added extension '*77' priority 12 to alarm_clock -- Added extension '*77' priority 13 to alarm_clock -- Added extension '*77' priority 14 to alarm_clock -- Added extension '*77' priority 15 to alarm_clock -- Added extension '*77' priority 16 to alarm_clock -- Added extension '*77' priority 17 to alarm_clock -- Added extension '*77' priority 18 to alarm_clock -- Added extension '*77' priority 19 to alarm_clock -- Added extension '*77' priority 20 to alarm_clock -- Added extension '*77' priority 21 to alarm_clock -- Added extension '*77' priority 22 to alarm_clock -- Added extension '*77' priority 23 to alarm_clock -- Added extension '*77' priority 24 to alarm_clock -- Added extension '*77' priority 25 to alarm_clock -- Added extension '*77' priority 26 to alarm_clock -- Added extension '*77' priority 27 to alarm_clock -- Added extension '*77' priority 28 to alarm_clock -- Added extension '*77' priority 29 to alarm_clock -- Added extension '*77' priority 30 to alarm_clock -- Added extension '*77' priority 31 to alarm_clock -- Added extension '*77' priority 32 to alarm_clock -- Added extension '*77' priority 33 to alarm_clock -- Added extension '*77' priority 34 to alarm_clock -- Added extension '*77' priority 35 to alarm_clock -- Added extension '*77' priority 36 to alarm_clock -- Added extension '*77' priority 37 to alarm_clock -- Added extension '*77' priority 38 to alarm_clock -- Added extension '*77' priority 39 to alarm_clock -- Added extension '*77' priority 40 to alarm_clock -- Added extension '*77' priority 41 to alarm_clock -- Added extension '*77' priority 42 to alarm_clock -- Added extension '*77' priority 43 to alarm_clock -- Added extension '*77' priority 44 to alarm_clock -- Added extension '*77' priority 45 to alarm_clock -- Added extension '*77' priority 46 to alarm_clock -- Added extension '*77' priority 47 to alarm_clock -- Added extension '*77' priority 48 to alarm_clock -- Added extension '*77' priority 49 to alarm_clock -- Added extension '*77' priority 50 to alarm_clock -- Added extension '*77' priority 51 to alarm_clock -- Added extension '*77' priority 52 to alarm_clock -- Added extension '*77' priority 53 to alarm_clock -- Added extension '*77' priority 54 to alarm_clock -- Added extension '*77' priority 55 to alarm_clock -- Added extension '*77' priority 56 to alarm_clock -- Added extension '*77' priority 57 to alarm_clock -- Added extension '*77' priority 58 to alarm_clock -- Added extension '*77' priority 59 to alarm_clock -- Added extension '*77' priority 60 to alarm_clock -- Added extension '*77' priority 61 to alarm_clock -- Added extension '*77' priority 62 to alarm_clock -- Added extension '*77' priority 63 to alarm_clock -- Added extension '*77' priority 64 to alarm_clock -- Added extension '*77' priority 65 to alarm_clock -- Added extension '*77' priority 66 to alarm_clock -- Added extension '*77' priority 67 to alarm_clock -- Registered extension context 'monitoring' -- Added extension '*88' priority 1 to monitoring -- Added extension '*88' priority 2 to monitoring -- Added extension '*88' priority 3 to monitoring -- Added extension '*88' priority 4 to monitoring -- Added extension '*88' priority 5 to monitoring -- Added extension '*88' priority 6 to monitoring -- Added extension '*88' priority 7 to monitoring -- Added extension '*88' priority 8 to monitoring -- Added extension '*88' priority 9 to monitoring -- Added extension '*88' priority 10 to monitoring -- Added extension '*88' priority 11 to monitoring -- Added extension '*88' priority 12 to monitoring -- Added extension '*88' priority 13 to monitoring -- Registered extension context 'paging' -- Added extension '*99' priority 1 to paging -- Added extension '*99' priority 2 to paging -- Added extension '*99' priority 3 to paging -- Added extension '*99' priority 4 to paging -- Added extension '*99' priority 5 to paging -- Added extension '*99' priority 6 to paging -- Added extension '*99' priority 7 to paging -- Added extension '*99' priority 8 to paging -- Added extension '*99' priority 9 to paging -- Added extension '*99' priority 10 to paging -- Added extension '*99' priority 11 to paging -- Added extension '*99' priority 12 to paging -- Added extension '*99' priority 13 to paging -- Added extension '*99' priority 14 to paging -- Added extension '*99' priority 15 to paging -- Added extension '*99' priority 16 to paging -- Added extension '*99' priority 17 to paging -- Added extension '*99' priority 18 to paging -- Added extension '*99' priority 19 to paging -- Added extension '*99' priority 20 to paging -- Added extension '*99' priority 21 to paging -- Added extension '*99' priority 22 to paging -- Added extension '*99' priority 23 to paging -- Added extension '*99' priority 24 to paging -- Added extension '*99' priority 25 to paging -- Added extension '*99' priority 26 to paging -- Added extension '*99' priority 27 to paging -- Added extension '*99' priority 28 to paging -- Added extension '*99' priority 29 to paging -- Added extension '*99' priority 30 to paging -- Added extension '*99' priority 31 to paging -- Added extension '*99' priority 32 to paging -- Added extension '*99' priority 33 to paging -- Added extension '*99' priority 34 to paging -- Added extension '*99' priority 35 to paging -- Added extension '*99' priority 36 to paging -- Added extension '*99' priority 37 to paging -- Added extension '_*99.' priority 1 to paging -- Added extension '_*99.' priority 2 to paging -- Added extension '_*99.' priority 3 to paging -- Added extension '_*97.' priority 1 to paging -- Added extension '_*97.' priority 2 to paging -- Added extension '_*97.' priority 3 to paging -- Registered extension context 'speed_dial' -- Added extension 'sd' priority 1 to speed_dial -- Added extension 'sd' priority 2 to speed_dial -- Added extension 'sd' priority 3 to speed_dial -- Added extension 'sd' priority 4 to speed_dial -- Added extension 'sd' priority 5 to speed_dial -- Added extension 'sd' priority 6 to speed_dial -- Added extension 'sd' priority 7 to speed_dial -- Added extension 'sd' priority 8 to speed_dial -- Added extension 'sd' priority 9 to speed_dial -- Added extension 'sd' priority 10 to speed_dial -- Added extension 'sd' priority 11 to speed_dial -- Added extension '_*X' priority 1 to speed_dial -- Added extension '_*X' priority 2 to speed_dial -- Registered extension context 'call_parking' -- Added extension '*700' priority 1 to call_parking -- Added extension '*700' priority 2 to call_parking -- Added extension '*700' priority 3 to call_parking -- Added extension '*700' priority 4 to call_parking -- Added extension '*700' priority 5 to call_parking -- Added extension '_*70X' priority 1 to call_parking -- Added extension '_*70X' priority 2 to call_parking -- Added extension '_*70X' priority 3 to call_parking -- Added extension '_*70X' priority 4 to call_parking -- Added extension '_*70X' priority 5 to call_parking -- Added extension '_*70X' priority 6 to call_parking -- Added extension '_*70X' priority 7 to call_parking -- Added extension '_*70X' priority 8 to call_parking -- Added extension '_*70X' priority 9 to call_parking -- Added extension '_*70X' priority 10 to call_parking -- Added extension '_*70X' priority 11 to call_parking -- Added extension '_*70X' priority 12 to call_parking -- Added extension '_*70X' priority 13 to call_parking -- Registered extension context 'test' -- Added extension '*9999' priority 1 to test -- Added extension '*6001' priority 1 to test -- Added extension '*338' priority 1 to test -- Added extension '*339' priority 1 to test -- Registered extension context 'sub-stdexten' -- Including context 'origination' in context 'sub-stdexten' -- Added extension '_X.' priority 1 to sub-stdexten -- Added extension '_X.' priority 2 to sub-stdexten -- Added extension '_X.' priority 3 to sub-stdexten -- Added extension '_X.' priority 4 to sub-stdexten -- Added extension '_X.' priority 5 to sub-stdexten -- Added extension '_X.' priority 6 to sub-stdexten -- Added extension '_X.' priority 7 to sub-stdexten -- Added extension '_X.' priority 8 to sub-stdexten -- Added extension '_X.' priority 9 to sub-stdexten -- Added extension '_X.' priority 10 to sub-stdexten -- Added extension '_X.' priority 11 to sub-stdexten -- Added extension '_X.' priority 12 to sub-stdexten -- Added extension '_X.' priority 13 to sub-stdexten -- Added extension '_X.' priority 14 to sub-stdexten -- Added extension '_X.' priority 15 to sub-stdexten -- Added extension '_X.' priority 16 to sub-stdexten -- Added extension '_X.' priority 17 to sub-stdexten -- Added extension '_X.' priority 18 to sub-stdexten -- Added extension '_X.' priority 19 to sub-stdexten -- Added extension '_X.' priority 20 to sub-stdexten -- Added extension '_X.' priority 21 to sub-stdexten -- Added extension '_X.' priority 22 to sub-stdexten -- Added extension '_X.' priority 23 to sub-stdexten -- Added extension '_X.' priority 24 to sub-stdexten -- Added extension '_X.' priority 25 to sub-stdexten -- Added extension '_X.' priority 26 to sub-stdexten -- Added extension '_X.' priority 27 to sub-stdexten -- Added extension '_X.' priority 28 to sub-stdexten -- Added extension '_X.' priority 29 to sub-stdexten -- Added extension '_X.' priority 30 to sub-stdexten -- Added extension '_X.' priority 31 to sub-stdexten -- Added extension '_X.' priority 32 to sub-stdexten -- Added extension '_X.' priority 33 to sub-stdexten -- Added extension '_X.' priority 34 to sub-stdexten -- Added extension '_X.' priority 35 to sub-stdexten -- Added extension '_X.' priority 36 to sub-stdexten -- Added extension '_X.' priority 37 to sub-stdexten -- Added extension '_X.' priority 38 to sub-stdexten -- Added extension '_X.' priority 39 to sub-stdexten -- Added extension '_X.' priority 40 to sub-stdexten -- Added extension '_X.' priority 41 to sub-stdexten -- Added extension '_X.' priority 42 to sub-stdexten -- Added extension '_X.' priority 43 to sub-stdexten -- Added extension '_X.' priority 44 to sub-stdexten -- Added extension '_X.' priority 45 to sub-stdexten -- Added extension '_X.' priority 46 to sub-stdexten -- Added extension '_X.' priority 47 to sub-stdexten -- Added extension '_X.' priority 48 to sub-stdexten -- Added extension '_X.' priority 49 to sub-stdexten -- Added extension '_X.' priority 50 to sub-stdexten -- Added extension '_X.' priority 51 to sub-stdexten -- Added extension '_X.' priority 52 to sub-stdexten -- Added extension '_X.' priority 53 to sub-stdexten -- Added extension '_X.' priority 54 to sub-stdexten -- Added extension '_X.' priority 55 to sub-stdexten -- Added extension '_X.' priority 56 to sub-stdexten -- Added extension '_X.' priority 57 to sub-stdexten -- Added extension '_X.' priority 58 to sub-stdexten -- Added extension '_X.' priority 59 to sub-stdexten -- Added extension '_X.' priority 60 to sub-stdexten -- Added extension '_X.' priority 61 to sub-stdexten -- Added extension '_X.' priority 62 to sub-stdexten -- Added extension '_X.' priority 63 to sub-stdexten -- Added extension '_X.' priority 64 to sub-stdexten -- Added extension '_X.' priority 65 to sub-stdexten -- Added extension '_X.' priority 66 to sub-stdexten -- Added extension '_X.' priority 67 to sub-stdexten -- Added extension '_X.' priority 68 to sub-stdexten -- Added extension '_X.' priority 69 to sub-stdexten -- Added extension 'ring_remote' priority 1 to sub-stdexten -- Added extension 'ring_remote' priority 2 to sub-stdexten -- Added extension 'ring_remote' priority 3 to sub-stdexten -- Added extension 'error' priority 1 to sub-stdexten -- Added extension 'error' priority 2 to sub-stdexten -- Added extension 'error' priority 3 to sub-stdexten -- Added extension 'a' priority 1 to sub-stdexten -- Added extension 'a' priority 2 to sub-stdexten -- Added extension 'a' priority 3 to sub-stdexten -- Added extension 'a' priority 4 to sub-stdexten -- Added extension 'o' priority 1 to sub-stdexten -- Added extension 'o' priority 2 to sub-stdexten -- Added extension 'o' priority 3 to sub-stdexten -- Added extension 'o' priority 4 to sub-stdexten -- Added extension 'o' priority 5 to sub-stdexten -- Added extension 'o' priority 6 to sub-stdexten -- Added extension 'o' priority 7 to sub-stdexten -- Added extension 'o' priority 8 to sub-stdexten -- Added extension 'o' priority 9 to sub-stdexten -- Added extension 'o' priority 10 to sub-stdexten -- Added extension 'o' priority 11 to sub-stdexten -- Added extension 'o' priority 12 to sub-stdexten -- Added extension 'o' priority 13 to sub-stdexten -- Added extension 'h' priority 1 to sub-stdexten -- Added extension 'h' priority 2 to sub-stdexten -- Added extension 'h' priority 3 to sub-stdexten -- Added extension 'h' priority 4 to sub-stdexten -- Added extension 'h' priority 5 to sub-stdexten -- Added extension 'h' priority 6 to sub-stdexten -- Added extension 'h' priority 7 to sub-stdexten -- Added extension 'h' priority 8 to sub-stdexten -- Added extension 'error-invite_timeout' priority 1 to sub-stdexten -- Added extension 'error-invite_timeout' priority 2 to sub-stdexten -- Registered extension context 'origination' -- Added extension '9911' priority 1 to origination -- Added extension '911' priority 1 to origination -- Added extension '911' priority 2 to origination -- Added extension '911' priority 3 to origination -- Added extension '911' priority 4 to origination -- Added extension '911' priority 5 to origination -- Added extension '911' priority 6 to origination -- Added extension '911' priority 7 to origination -- Added extension '911' priority 8 to origination -- Added extension '911' priority 9 to origination -- Added extension '911' priority 10 to origination -- Added extension '911' priority 11 to origination -- Added extension '911' priority 12 to origination -- Added extension '911' priority 13 to origination -- Added extension '911' priority 14 to origination -- Added extension 'emerg_no_cid' priority 1 to origination -- Added extension 'emerg_no_cid' priority 2 to origination -- Added extension 'emerg_no_cid' priority 3 to origination -- Added extension 'emerg_no_cid' priority 4 to origination -- Added extension '102_lmentinc' priority -1 to origination -- Added extension '103_lmentinc' priority -1 to origination -- Added extension '101_lmentinc' priority -1 to origination -- Added extension '105_lmentinc' priority -1 to origination -- Added extension '104_lmentinc' priority -1 to origination -- Added extension '100_lmentinc' priority -1 to origination -- Added extension '106_lmentinc' priority -1 to origination -- Added extension '202_telcel' priority -1 to origination -- Added extension '120_telcel' priority -1 to origination -- Added extension '301_telcel' priority -1 to origination -- Added extension '311_telcel' priority -1 to origination -- Added extension '501_telcel' priority -1 to origination -- Added extension '650_telcel' priority -1 to origination -- Added extension '101_telcel' priority -1 to origination -- Added extension '008_telcel' priority -1 to origination -- Added extension '102_telcel' priority -1 to origination -- Added extension '5756_telcel' priority -1 to origination -- Added extension '111_telcel' priority -1 to origination -- Added extension '8635830281_telcel' priority -1 to origination -- Added extension '116_telcel' priority -1 to origination -- Added extension '272_telcel' priority -1 to origination -- Added extension '108_telcel' priority -1 to origination -- Added extension '109_telcel' priority -1 to origination -- Added extension '4444_telcel' priority -1 to origination -- Added extension '444_telcel' priority -1 to origination -- Added extension '3055374305_telcel' priority -1 to origination -- Added extension '8635830258_telcel' priority -1 to origination -- Added extension '8635830275_telcel' priority -1 to origination -- Added extension '112_telcel' priority -1 to origination -- Added extension '8635830294_telcel' priority -1 to origination -- Added extension '190_telcel' priority -1 to origination -- Added extension '118_telcel' priority -1 to origination -- Added extension '222_telcel' priority -1 to origination -- Added extension '2006_telcel' priority -1 to origination -- Added extension '110_telcel' priority -1 to origination -- Added extension '2007_telcel' priority -1 to origination -- Added extension '8635830295_telcel' priority -1 to origination -- Added extension '407_telcel' priority -1 to origination -- Added extension '999_telcel' priority -1 to origination -- Added extension '203_telcel' priority -1 to origination -- Added extension '106_telcel' priority -1 to origination -- Added extension '8888_telcel' priority -1 to origination -- Added extension '1007_telcel' priority -1 to origination -- Added extension '9999_telcel' priority -1 to origination -- Added extension '1002_telcel' priority -1 to origination -- Added extension '104_telcel' priority -1 to origination -- Added extension '1001_telcel' priority -1 to origination -- Added extension '107_telcel' priority -1 to origination -- Added extension '667_telcel' priority -1 to origination -- Added extension '777_telcel' priority -1 to origination -- Added extension '007_telcel' priority -1 to origination -- Added extension '201_telcel' priority -1 to origination -- Added extension '601_telcel' priority -1 to origination -- Added extension '103_telcel' priority -1 to origination -- Added extension '500_telcel' priority -1 to origination -- Added extension '205_telcel' priority -1 to origination -- Including context 'standard_features' in context 'origination' -- Added extension '_011.' priority 1 to origination -- Added extension '_011.' priority 2 to origination -- Added extension '_011.' priority 3 to origination -- Added extension '_011.' priority 4 to origination -- Added extension '_011.' priority 5 to origination -- Added extension '_011.' priority 6 to origination -- Added extension '_1NXXNXXXXXX' priority 1 to origination -- Added extension '_1NXXNXXXXXX' priority 2 to origination -- Added extension '_1NXXNXXXXXX' priority 3 to origination -- Added extension '_1NXXNXXXXXX' priority 4 to origination -- Added extension '_1NXXNXXXXXX' priority 5 to origination -- Added extension '_1NXXNXXXXXX' priority 6 to origination -- Added extension '_1NXXNXXXXXX' priority 7 to origination -- Added extension '_1NXXNXXXXXX' priority 8 to origination -- Added extension '_NXXNXXXXXX' priority 1 to origination -- Added extension '_NXXXXXX' priority 1 to origination -- Added extension '_NXXXXXX' priority 2 to origination -- Added extension '_NXXXXXX' priority 3 to origination -- Added extension '_NXXXXXX' priority 4 to origination -- Added extension '_NXXXXXX' priority 5 to origination -- Added extension '_NXXXXXX' priority 6 to origination -- Added extension '_NXXXXXX' priority 7 to origination -- Added extension '_NXXXXXX' priority 8 to origination -- Added extension '_NXXXXXX' priority 9 to origination -- Added extension '_NXXXXXX' priority 10 to origination -- Added extension '_X.' priority 1 to origination -- Added extension '_X.' priority 2 to origination -- Added extension '_X.' priority 3 to origination -- Added extension '_X.' priority 4 to origination -- Added extension '_X.' priority 5 to origination -- Added extension '_X.' priority 6 to origination -- Added extension 'out' priority 1 to origination -- Added extension 'out' priority 2 to origination -- Added extension 'out' priority 3 to origination -- Added extension 'out' priority 4 to origination -- Added extension 'out' priority 5 to origination -- Added extension 'out' priority 6 to origination -- Added extension 'out' priority 7 to origination -- Added extension 'out' priority 8 to origination -- Added extension 'out' priority 9 to origination -- Added extension 'out' priority 10 to origination -- Added extension 'out' priority 11 to origination -- Added extension 'out' priority 12 to origination -- Added extension 'out' priority 13 to origination -- Added extension 'out' priority 14 to origination -- Added extension 'out' priority 15 to origination -- Added extension 'out' priority 16 to origination -- Added extension 'out' priority 17 to origination -- Added extension 'out' priority 18 to origination -- Added extension 'out' priority 19 to origination -- Added extension 'out' priority 20 to origination -- Added extension 'out' priority 21 to origination -- Added extension 'out' priority 22 to origination -- Added extension 'out' priority 23 to origination -- Added extension 'out' priority 24 to origination -- Added extension 'out' priority 25 to origination -- Added extension 'out' priority 26 to origination -- Added extension 'out' priority 27 to origination -- Added extension 'out' priority 28 to origination -- Added extension 'int' priority 1 to origination -- Added extension 'int' priority 2 to origination -- Added extension 'int' priority 3 to origination -- Added extension 'int' priority 4 to origination -- Added extension 'int' priority 5 to origination -- Added extension 'int' priority 6 to origination -- Added extension 'int' priority 7 to origination -- Added extension 'int' priority 8 to origination -- Added extension 'int' priority 9 to origination -- Added extension 'int' priority 10 to origination -- Added extension 'int' priority 11 to origination -- Added extension 'int' priority 12 to origination -- Added extension 'int' priority 13 to origination -- Added extension 'int' priority 14 to origination -- Added extension 'not_assigned' priority 1 to origination -- Added extension 'not_assigned' priority 2 to origination -- Added extension 'not_assigned' priority 3 to origination -- Added extension 'not_assigned' priority 4 to origination -- Added extension 'not_assigned' priority 5 to origination -- Added extension 'disconnected' priority 1 to origination -- Added extension 'disconnected' priority 2 to origination -- Added extension 'disconnected' priority 3 to origination -- Added extension 'disconnected' priority 4 to origination -- Added extension 'restricted' priority 1 to origination -- Added extension 'restricted' priority 2 to origination -- Added extension 'restricted' priority 3 to origination -- Added extension 'h' priority 1 to origination -- Added extension 'h' priority 2 to origination -- Added extension 'h' priority 3 to origination -- Added extension 'h' priority 4 to origination -- Added extension 'h' priority 5 to origination -- Added extension 'h' priority 6 to origination -- Added extension 'h' priority 7 to origination -- Added extension 'h' priority 8 to origination -- Added extension 'h' priority 9 to origination -- Registered extension context 'vm-exitcontext' -- Added extension '1' priority 1 to vm-exitcontext -- Added extension '1' priority 2 to vm-exitcontext -- Added extension '1' priority 3 to vm-exitcontext -- Added extension '1' priority 4 to vm-exitcontext -- Added extension '1' priority 5 to vm-exitcontext -- Added extension '1' priority 6 to vm-exitcontext -- Registered extension context 'hash-test' -- Added extension 's' priority 1 to hash-test -- Added extension 's' priority 2 to hash-test -- Added extension 's' priority 3 to hash-test -- Added extension 's' priority 4 to hash-test -- Added extension 's' priority 5 to hash-test -- Added extension 's' priority 6 to hash-test -- Registered extension context 'alarmclock' -- Added extension '_1-.' priority 1 to alarmclock -- Added extension '_1-.' priority 2 to alarmclock -- Added extension '_1-.' priority 3 to alarmclock -- Added extension '_1-.' priority 4 to alarmclock -- Added extension '_1-.' priority 5 to alarmclock -- Added extension '_1-.' priority 6 to alarmclock -- Added extension '_1-.' priority 7 to alarmclock -- Added extension '_1-.' priority 8 to alarmclock -- Added extension '_1-.' priority 9 to alarmclock -- Added extension '_1-.' priority 10 to alarmclock -- Added extension '_1-.' priority 11 to alarmclock -- Added extension 'snooze' priority 1 to alarmclock -- Added extension 'snooze' priority 2 to alarmclock -- Added extension 'snooze' priority 3 to alarmclock -- Added extension 'h' priority 1 to alarmclock -- Added extension 'h' priority 2 to alarmclock -- Registered extension context 'from-voip' -- Added extension '_NXXNXXXXXX' priority 1 to from-voip -- Added extension '_NXXNXXXXXX' priority 2 to from-voip -- Added extension '_NXXNXXXXXX' priority 3 to from-voip -- Added extension '_NXXNXXXXXX' priority 4 to from-voip -- Added extension '_1NXXNXXXXXX' priority 1 to from-voip -- Registered extension context 'from-pstn' -- Added extension 't' priority 1 to from-pstn -- Added extension 'i' priority 1 to from-pstn -- Registered extension context 'from-cluster' [Mar 8 10:37:15] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 64 -- Added extension '_.' priority 1 to from-cluster [Mar 8 10:37:15] WARNING[8805]: pbx_config.c:2292 pbx_load_config: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 65 -- Added extension '_.' priority 2 to from-cluster -- Registered extension context 'sub-from-cluster' -- Added extension 's' priority 1 to sub-from-cluster -- Added extension 's' priority 2 to sub-from-cluster -- Added extension 's' priority 3 to sub-from-cluster -- Added extension 's' priority 4 to sub-from-cluster -- Added extension 's' priority 5 to sub-from-cluster -- Added extension 's' priority 6 to sub-from-cluster -- Added extension 's' priority 7 to sub-from-cluster -- Added extension 'h' priority 1 to sub-from-cluster -- Added extension 'h' priority 2 to sub-from-cluster -- Registered extension context 'default' -- Added extension '_X.' priority 1 to default -- Added extension '_X.' priority 2 to default -- Added extension '_X.' priority 3 to default pbx_config.so => (Text Extension Configuration) == Registered channel type 'Local' (Local Proxy Channel Driver) chan_local.so => (Local Proxy Channel) == Registered application 'NoCDR' app_cdr.so => (Tell Asterisk to not maintain a CDR for the current call) == Registered application 'SayUnixTime' == Registered application 'DateTime' app_sayunixtime.so => (Say time) == Registered custom function REALTIME func_realtime.so => (Read/Write values from a RealTime repository) == Registered application 'Read' app_read.so => (Read Variable Application) == Registered file format g726-40, extension(s) g726-40 == Registered file format g726-32, extension(s) g726-32 == Registered file format g726-24, extension(s) g726-24 == Registered file format g726-16, extension(s) g726-16 format_g726.so => (Raw G.726 (16/24/32/40kbps) data) == Registered application 'Page' app_page.so => (Page Multiple Phones) == Registered custom function FIELDQTY == Registered custom function FILTER == Registered custom function REGEX == Registered custom function ARRAY == Registered custom function QUOTE == Registered custom function LEN == Registered custom function STRFTIME == Registered custom function STRPTIME == Registered custom function EVAL == Registered custom function KEYPADHASH == Registered custom function SPRINTF func_strings.so => (String handling dialplan functions) pbx_spool.so => (Outgoing Spool Support) == Registered application 'Flash' app_flash.so => (Flash channel application) == Registered application 'Log' == Registered application 'Verbose' app_verbose.so => (Send verbose output) == Registered application 'DBdel' == Registered application 'DBdeltree' app_db.so => (Database Access Functions) == Registered custom function MATH func_math.so => (Mathematical dialplan function) == Registered application 'ChanIsAvail' app_chanisavail.so => (Check channel availability) == Registered custom function RAND func_rand.so => (Random number dialplan function) == Registered application 'Dictate' app_dictate.so => (Virtual Dictation Machine) == Parsing '/etc/asterisk/followme.conf': Found == Registered application 'FollowMe' app_followme.so => (Find-Me/Follow-Me Application) == Registered file format h263, extension(s) h263 format_h263.so => (Raw H.263 data) == Registered application 'ParkAndAnnounce' app_parkandannounce.so => (Call Parking and Announce Application) == Registered custom function BASE64_ENCODE == Registered custom function BASE64_DECODE func_base64.so => (base64 encode/decode dialplan functions) == Registered custom function MUSICCLASS func_moh.so => (Music-on-hold dialplan function) == Registered application 'ChannelRedirect' app_channelredirect.so => (Channel Redirect) == Registered application 'Pickup' app_directed_pickup.so => (Directed Call Pickup Application) == Registered application 'Random' app_random.so => (Random goto) == Registered custom function CALLERID func_callerid.so => (Caller ID related dialplan function) == Registered application 'BackgroundDetect' app_talkdetect.so => (Playback with Talk Detection) == Registered application 'TrySystem' == Registered application 'System' app_system.so => (Generic System() application) == Registered file format wav, extension(s) wav format_wav.so => (Microsoft WAV format (8000Hz Signed Linear)) [Mar 8 10:37:15] WARNING[8805]: app_festival.c:545 load_module: No such configuration file festival.conf == Registered application 'SpeechCreate' == Registered application 'SpeechLoadGrammar' == Registered application 'SpeechUnloadGrammar' == Registered application 'SpeechActivateGrammar' == Registered application 'SpeechDeactivateGrammar' == Registered application 'SpeechStart' == Registered application 'SpeechBackground' == Registered application 'SpeechDestroy' == Registered application 'SpeechProcessingSound' == Registered custom function SPEECH == Registered custom function SPEECH_SCORE == Registered custom function SPEECH_TEXT == Registered custom function SPEECH_GRAMMAR == Registered custom function SPEECH_ENGINE app_speech_utils.so => (Dialplan Speech Applications) == Registered application 'DumpChan' app_dumpchan.so => (Dump Info About The Calling Channel) == Registered custom function BLACKLIST == Registered application 'LookupBlacklist' app_lookupblacklist.so => (Look up Caller*ID name/number from blacklist database) == Registered application 'Exec' == Registered application 'TryExec' == Registered application 'ExecIf' app_exec.so => (Executes dialplan applications) == Registered application 'Record' app_record.so => (Trivial Record Application) == Parsing '/etc/asterisk/func_odbc.conf': Found == Registered custom function ODBC_SQL == Registered custom function ODBC_BRANDID == Registered custom function HUNT_INITIAL == Registered custom function HUNT_MEMBERS_COUNT == Registered custom function HUNT_MEMBERS == Registered custom function HUNT_RRM_CLEAR == Registered custom function HUNT_GROUP_MEMBERS_COUNT == Registered custom function HUNT_GROUP_MEMBERS == Registered custom function HUNT_NOANS_DEST == Registered custom function ROUTE_ROUTE == Registered custom function ROUTE_DEVICE == Registered custom function ROUTE_DESTINATION == Registered custom function R_CMD == Registered custom function R_ADR == Registered custom function ROUTE_MEMBER == Registered custom function ROUTE_CLASS == Registered custom function ROUTE_7_DIGIT_ALLOWED == Registered custom function ROUTE_WHO == Registered custom function VM_ACTIVE == Registered custom function VM_PIN == Registered custom function QUEUE_INFO == Registered custom function QUEUE_NOANS_DEST == Registered custom function PHONE_EMERGENCY_CID == Registered custom function PHONE_DND == Registered custom function PHONE_FWD_ALL_CALLS == Registered custom function PHONE_FWD_NUMBER == Registered custom function PHONE_FWD_INFO == Registered custom function PHONE_FWD_ACTIVE == Registered custom function PHONE_RING_CYCLE == Registered custom function GROUP_RING_CYCLE == Registered custom function PHONE_MAX_TRUNKS == Registered custom function PHONE_STATUS == Registered custom function PHONE_AVAILCHAN == Registered custom function PHONE_LAST_NUM == Registered custom function PHONE_LAST_CALL == Registered custom function PHONE_CONF == Registered custom function PHONE_USERNAME == Registered custom function PHONE_CID_BLOCK == Registered custom function PHONE_CID_BLOCK_SINGLE == Registered custom function PHONE_USER_ID == Registered custom function PHONE_ACCOUNT_CODE == Registered custom function PHONE_SPEED_DIAL == Registered custom function PHONE_ALLOWED_SPYING == Registered custom function PHONE_MONITOR_BLOCK == Registered custom function CID_CONTROL == Registered custom function PBX_MAX_TRUNKS == Registered custom function NAME_CHECK == Registered custom function SS_SELECTOR == Registered custom function SS_LOOKUP == Registered custom function SS_COUNT == Registered custom function ALARM_REMOVE == Registered custom function ALARM_SNOOZE == Registered custom function ALARM_UNSNOOZE == Registered custom function ALARM_LASTCALLED == Registered custom function ALARM_INSERT == Registered custom function CALLGROUP_DEVICE_CHK == Registered custom function CALLGROUP_DID_CHK == Registered custom function CALLGROUP_DEVICE_BLOCK == Registered custom function CALLGROUP_DID_BLOCK == Registered custom function CALLGROUP_DID_PBX == Registered custom function PAGER_GROUP_MEMBERS == Registered custom function INTERCOM_GROUP_MEMBERS == Registered custom function PARKING_RESV_SPACE == Registered custom function PARKING_DELETE_LOT == Registered custom function PARKING_SPACES == Registered custom function ACCESS_CONTROL_DATA == Registered custom function FEATURE_STATUS == Registered custom function SQL_ESC == Registered custom function HASH == Registered custom function HASHKEYS == Registered application 'ClearHash' func_odbc.so => (ODBC lookups) == Parsing '/etc/asterisk/sip.conf': Found == SIP Listening on 0.0.0.0:5060 == Using SIP TOS: none == Parsing '/etc/asterisk/sip_notify.conf': Found == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) == Registered application 'SIPDtmfMode' == Registered application 'SIPAddHeader' == Registered custom function SIP_HEADER == Registered custom function SIPPEER == Registered custom function SIPCHANINFO == Registered custom function CHECKSIPDOMAIN == Manager registered action SIPpeers == Manager registered action SIPshowpeer chan_sip.so => (Session Initiation Protocol (SIP)) == Parsing '/etc/asterisk/cdr.conf': Found == Registered application 'ForkCDR' app_forkcdr.so => (Fork The CDR into 2 separate entities) == Registered application 'RealTimeUpdate' == Registered application 'RealTime' app_realtime.so => (Realtime Data Lookup/Rewrite) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_zap: using generic PLC [Mar 8 10:37:15] NOTICE[8805]: codec_zap.c:365 find_transcoders: No Zaptel transcoder support! codec_zap.so => (Generic Zaptel Transcoder Codec Translator) == Registered custom function CUT == Registered custom function SORT func_cut.so => (Cut out information from a string) == Registered application 'While' == Registered application 'EndWhile' == Registered application 'ExitWhile' == Registered application 'ContinueWhile' app_while.so => (While Loops and Conditional Execution) == Registered application 'Zapateller' app_zapateller.so => (Block Telemarketers with Special Information Tone) == Registered application 'MacroExit' == Registered application 'MacroIf' == Registered application 'MacroExclusive' == Registered application 'Macro' app_macro.so => (Extension Macros) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_ulaw: using generic PLC [Mar 8 10:37:15] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 codec_ulaw.so => (mu-Law Coder/Decoder) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_gsm: using generic PLC [Mar 8 10:37:15] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 160 format 6 == Registered translator 'gsmtolin' from format gsm to slin, cost 2 == Registered translator 'lintogsm' from format slin to gsm, cost 4 codec_gsm.so => (GSM Coder/Decoder) == Registered application 'SetCallerPres' == Registered application 'SetCallerID' app_setcallerid.so => (Set CallerID Application) == Registered custom function MD5 == Registered custom function CHECK_MD5 func_md5.so => (MD5 digest dialplan functions) == Registered file format wav49, extension(s) WAV|wav49 format_wav_gsm.so => (Microsoft WAV format (Proprietary GSM)) pbx_realtime.so => (Realtime Switch) == Registered application 'MixMonitor' == Registered application 'StopMixMonitor' app_mixmonitor.so => (Mixed Audio Monitoring Application) == Registered custom function ENV == Registered custom function STAT func_env.so => (Environment/filesystem dialplan functions) == Registered application 'UserEvent' app_userevent.so => (Custom User Event Application) == Parsing '/etc/asterisk/queues.conf': Found == Registered application 'Queue' == Registered application 'AddQueueMember' == Registered application 'RemoveQueueMember' == Registered application 'PauseQueueMember' == Registered application 'UnpauseQueueMember' == Registered application 'QueueLog' == Manager registered action Queues == Manager registered action QueueStatus == Manager registered action QueueAdd == Manager registered action QueueRemove == Manager registered action QueuePause == Registered custom function QUEUEAGENTCOUNT == Registered custom function QUEUE_MEMBER_COUNT == Registered custom function QUEUE_MEMBER_LIST == Registered custom function QUEUE_WAITING_COUNT app_queue.so => (True Call Queueing) == Registered application 'GetCPEID' app_getcpeid.so => (Get ADSI CPE ID) == Parsing '/etc/asterisk/codecs.conf': Found -- codec_lpc10: using generic PLC [Mar 8 10:37:15] WARNING[8805]: translate.c:675 __ast_register_translator: plc_samples 180 format 6 == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 4 == Registered translator 'lintolpc10' from format slin to lpc10, cost 8 codec_lpc10.so => (LPC10 2.4kbps Coder/Decoder) == Parsing '/etc/asterisk/say.conf': Found == Registered application 'Playback' app_playback.so => (Sound File Playback Application) == Registered application 'DeadAGI' == Registered application 'EAGI' == Registered application 'AGI' res_agi.so => (Asterisk Gateway Interface (AGI)) == Registered application 'ChanSpy' == Registered application 'ExtenSpy' app_chanspy.so => (Listen to the audio of an active channel) == Registered application 'ZapSendKeypadFacility' == Parsing '/etc/asterisk/zapata.conf': Found -- Automatically generated pseudo channel == Registered channel type 'Zap' (Zapata Telephony Driver w/PRI) == Manager registered action ZapTransfer == Manager registered action ZapHangup == Manager registered action ZapDialOffhook == Manager registered action ZapDNDon == Manager registered action ZapDNDoff == Manager registered action ZapShowChannels == Manager registered action ZapRestart chan_zap.so => (Zapata Telephony) == Registered application 'SendImage' app_image.so => (Image Transmission Application) == Registered application 'MP3Player' app_mp3.so => (Silly MP3 Application) == Registered application 'SMS' app_sms.so => (SMS/PSTN handler) == Registered custom function IAXPEER == Registered application 'IAX2Provision' == Manager registered action IAXpeers == Manager registered action IAXnetstats == Parsing '/etc/asterisk/iax.conf': Found == Parsing '/etc/asterisk/include/incs/iaxusers.inc': Found -- Registered extension context 'clients' == Using TOS bits 0 == Binding IAX2 to default address 0.0.0.0:4569 [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lmadsen2#lmentinc' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lmadsen4#lmentinc' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'tasgi#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ktoepel#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'alucas#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'hholmes0102#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ptest#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'tshirley#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'cturner#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'scruz#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'tdialer#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'egeorge#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'sholmes#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ssteve#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lbull#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ttelcel0102#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ttelcel010203#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'rcashner#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ralexander#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'psullivan#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'asupport#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'aeurope#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'hholmes01#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'aeurope2#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'mdouglas#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'ohbholmes#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lholmesh#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'chome#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'nlimbaga#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lholmes#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'lmadsen#telcel' in family 'IAX/Registry' [Mar 8 10:37:15] DEBUG[8805]: db.c:197 ast_db_get: Unable to find key 'sswafford#telcel' in family 'IAX/Registry' == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) == 10 helper threaads started == IAX Ready and Listening == Loaded firmware 'iaxy.bin' [Mar 8 10:37:15] NOTICE[8805]: iax2-provision.c:518 iax_provision_reload: No IAX provisioning configuration found, IAX provisioning disabled. chan_iax2.so => (Inter Asterisk eXchange (Ver 2)) == Registered application 'ZapRAS' app_zapras.so => (Zap RAS Application) == Registered file format vox, extension(s) vox format_vox.so => (Dialogic VOX (ADPCM) File Format) == Registered application 'SoftHangup' app_softhangup.so => (Hangs up the requested channel) == Registered custom function ISNULL == Registered custom function SET == Registered custom function EXISTS == Registered custom function IF == Registered custom function IFTIME func_logic.so => (Logical dialplan functions) == Registered application 'NBScat' app_nbscat.so => (Silly NBS Stream Application) == Registered application 'Transfer' app_transfer.so => (Transfer) == Registered file format h264, extension(s) h264 format_h264.so => (Raw H.264 data) Asterisk Ready. *CLI> set core set debug 4 Core debug was 0 and is now 4 *CLI> [Mar 8 10:37:24] NOTICE[8817]: pbx_dundi.c:2820 destroy_trans: Peer '00:11:43:5a:3e:f5' has become UNREACHABLE! [Mar 8 10:37:24] NOTICE[8817]: pbx_dundi.c:2820 destroy_trans: Peer '00:11:43:ce:09:c2' has become UNREACHABLE! [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:2573 do_setnat: Setting NAT on RTP to Off [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:2583 do_setnat: Setting NAT on UDPTL to Off [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:4308 sip_alloc: Allocating new SIP dialog for f5b30000-389e25e4@switch1-mia.mixnetworks.net - INVITE (With RTP) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:2573 do_setnat: Setting NAT on RTP to Off [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:2583 do_setnat: Setting NAT on UDPTL to Off [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:5123 process_sdp: T38 state changed to 0 on channel [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:5203 process_sdp: We're settling with these formats: 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:13378 handle_request_invite: Checking SIP call limits for device lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3001 update_call_counter: Updating call counter for incoming call [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3803 sip_new: *** Our native formats are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3804 sip_new: *** Joint capabilities are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3805 sip_new: *** Our capabilities are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3806 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:3829 sip_new: This channel will not be able to handle video. [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:7935 build_route: build_route: Record-Route hop: [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:13453 handle_request_invite: SIP/lmadsen#lmentinc-0990d4f8: New call is still down.... Trying... [Mar 8 10:37:39] DEBUG[8820]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:39] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@origination:1] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Internal Call Handling --> 102") in new stack Internal Call Handling --> 102 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'YES' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@origination:2] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Call was member originated: YES") in new stack Call was member originated: YES [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [102@origination:3] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?int|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@origination:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "E=102") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@origination:5] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-who|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:origination|102|6' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-who:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-who:2] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Setting USERNAME and PBX via CHANNEL: SIP/lmadsen#lmentinc-0990d4f8") in new stack Setting USERNAME and PBX via CHANNEL: SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'SIP/lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-who:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "TMP=SIP/lmadsen#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-who:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "TMP=lmadsen#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-who:5] Set("SIP/lmadsen#lmentinc-0990d4f8", "USERNAME=lmadsen") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-who:6] Set("SIP/lmadsen#lmentinc-0990d4f8", "PBX=lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-who:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Set USERNAME: lmadsen and PBX: lmentinc") in new stack Set USERNAME: lmadsen and PBX: lmentinc [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '6' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-who:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "USER_ID=6") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-who:9] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|SET USER_ID: 6") in new stack SET USER_ID: 6 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-who:10] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Goto' -- Executing [102@origination:6] Goto("SIP/lmadsen#lmentinc-0990d4f8", "int|1") in new stack -- Goto (origination,int,1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [int@origination:1] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|--> Handling call to internal extension 102") in new stack --> Handling call to internal extension 102 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [int@origination:2] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|...finding out who 102 is") in new stack ...finding out who 102 is [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [int@origination:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(EXT_INFO)=lmadsen2") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (user_name=lmadsen2) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (user_name=lmadsen2) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [int@origination:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(EXT_STATUS)=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (status=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (status=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [int@origination:5] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?not_assigned|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [int@origination:6] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?disconnected|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [int@origination:7] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-feature_status|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:origination|int|8' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-feature_status:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '6' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-feature_status:2] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-feature_status:3] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Setting HASH(feature_status) for lmadsen@lmentinc(6)") in new stack Setting HASH(feature_status) for lmadsen@lmentinc(6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,6,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-feature_status:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(feature_status)=1,6,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (pbx_id,user_id,alarm,cfwd,cid,cid_block,cid_block_single,dnd,intercom,last_num,last_call,mon,mon_block,mon_others,paging,pconf,rec_calls,rec_all_calls,sec_code,speed,fmfm,vm,hunt_group,call_queues,attendant,conf_rooms=1,6,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pbx_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (user_id=6) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (alarm=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cfwd=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block_single=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (dnd=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (intercom=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_num=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_call=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_others=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (paging=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pconf=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_all_calls=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (sec_code=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (speed=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (fmfm=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (vm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (hunt_group=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (call_queues=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (attendant=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (conf_rooms=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'conf_rooms,attendant,call_queues,hunt_group,vm,fmfm,speed,sec_code,rec_all_calls,rec_calls,pconf,paging,mon_others,mon_block,mon,last_call,last_num,intercom,dnd,cid_block_single,cid_block,cid,cfwd,alarm,user_id,pbx_id' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'HASH(feature_status) is set for lmadsen@lmentinc(6)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-feature_status:5] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|HASH(feature_status) is set for lmadsen@lmentinc(6)") in new stack HASH(feature_status) is set for lmadsen@lmentinc(6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Goto' -- Executing [s@sub-feature_status:6] Goto("SIP/lmadsen#lmentinc-0990d4f8", "return") in new stack -- Goto (sub-feature_status,s,7) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-feature_status:7] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [int@origination:8] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-access_control|s|1(no_chk)") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG1' to 'no_chk' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '1:origination|int|9' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-access_control:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-access_control start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:2] Set("SIP/lmadsen#lmentinc-0990d4f8", "FEATURE=no_chk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "NO_CHK=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '6' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-access_control:4] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-access_control:5] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Performing HASH(access_control) checks for lmadsen@lmentinc(6)") in new stack Performing HASH(access_control) checks for lmadsen@lmentinc(6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-access_control:6] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?error|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,6,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:7] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(access_control)=1,6,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (pbx_id,user_id,alarm,cfwd,cid,cid_block,cid_block_single,dnd,intercom,last_num,last_call,mon,mon_block,mon_others,paging,pconf,rec_calls,rec_all_calls,sec_code,speed,fmfm,vm,hunt_group,call_queues,attendant,conf_rooms=1,6,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pbx_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (user_id=6) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (alarm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cfwd=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block_single=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (dnd=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (intercom=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_num=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_call=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_others=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (paging=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pconf=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_all_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (sec_code=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (speed=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (fmfm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (vm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (hunt_group=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (call_queues=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (attendant=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (conf_rooms=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-access_control:8] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|HASH for access_control is now set") in new stack HASH for access_control is now set [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-access_control:9] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "skip") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,6,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-access_control:10] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?error|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Return()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-access_control:11] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Return()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [int@origination:9] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-monitoring|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:origination|int|10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-monitoring:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-monitoring start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-monitoring:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-monitoring,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-monitoring:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-monitoring:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CALL MONITORING MODULE ENABLED") in new stack CALL MONITORING MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-monitoring:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "MONITOR_BLOCK=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-monitoring:9] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8") in new stack Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-monitoring:10] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-monitoring:11] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [int@origination:10] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?post_last_num") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [int@origination:11] Set("SIP/lmadsen#lmentinc-0990d4f8", "PHONE_LAST_NUM(lmadsen|lmentinc)=102") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [int@origination:12] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "post_last_num") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [int@origination:13] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-stdexten|102|1(102|lmadsen2#lmentinc|lmentinc|102||INTERNAL)") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG1' to '102' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG2' to 'lmadsen2#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG3' to 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG4' to '102' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG5' to '' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG6' to 'INTERNAL' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '6:origination|int|14' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:2] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Delivering call from SIP/lmadsen#lmentinc-0990d4f8 to extension 102@lmentinc (lmadsen2#lmentinc)") in new stack Delivering call from SIP/lmadsen#lmentinc-0990d4f8 to extension 102@lmentinc (lmadsen2#lmentinc) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'SIP/lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "TMP=SIP/lmadsen#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "TMP=lmadsen#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:5] Set("SIP/lmadsen#lmentinc-0990d4f8", "DEVICE_FLAG=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:6] Set("SIP/lmadsen#lmentinc-0990d4f8", "ORIG_USERNAME=lmadsen") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:7] Set("SIP/lmadsen#lmentinc-0990d4f8", "ORIG_PBX=lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "PHONE_EXTEN=102") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:9] Set("SIP/lmadsen#lmentinc-0990d4f8", "__USERNAME=lmadsen2") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:10] Set("SIP/lmadsen#lmentinc-0990d4f8", "__PBX=lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:11] Set("SIP/lmadsen#lmentinc-0990d4f8", "__USER_ID=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:12] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-maxcallcheck|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|13' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-maxcallcheck:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-maxcallcheck start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-maxcallcheck:2] Set("SIP/lmadsen#lmentinc-0990d4f8", "GROUP(pbx)=lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-maxcallcheck:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "GROUP(lmentinc)=lmadsen2") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-maxcallcheck:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(USER_TRUNKS)=2") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (max_calls=2) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (max_calls=2) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-maxcallcheck:5] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Current channel usage count is: PBX(lmentinc) --> 1 of 10 DEVICE(lmadsen2) --> 1 of 2") in new stack Current channel usage count is: PBX(lmentinc) --> 1 of 10 DEVICE(lmadsen2) --> 1 of 2 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-maxcallcheck:6] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?pbx_gc_chk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-maxcallcheck:7] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?:too_many_trunks|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-maxcallcheck:8] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?cidchk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '10' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-maxcallcheck:9] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?:too_many_trunks|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-maxcallcheck:10] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Allowing call from SIP/lmadsen#lmentinc-0990d4f8 to lmadsen2@lmentinc as we have enough room") in new stack Allowing call from SIP/lmadsen#lmentinc-0990d4f8 to lmadsen2@lmentinc as we have enough room [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-maxcallcheck:11] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:13] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-feature_status|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|14' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-feature_status:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-feature_status:2] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-feature_status:3] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Setting HASH(feature_status) for lmadsen2@lmentinc(1)") in new stack Setting HASH(feature_status) for lmadsen2@lmentinc(1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,1,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-feature_status:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(feature_status)=1,1,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (pbx_id,user_id,alarm,cfwd,cid,cid_block,cid_block_single,dnd,intercom,last_num,last_call,mon,mon_block,mon_others,paging,pconf,rec_calls,rec_all_calls,sec_code,speed,fmfm,vm,hunt_group,call_queues,attendant,conf_rooms=1,1,0,0,1,0,0,0,1,1,1,1,1,1,1,1,1,0,1,1,0,1,1,1,1,1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pbx_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (user_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (alarm=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cfwd=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block_single=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (dnd=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (intercom=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_num=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_call=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_others=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (paging=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pconf=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_all_calls=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (sec_code=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (speed=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (fmfm=0) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (vm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (hunt_group=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (call_queues=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (attendant=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (conf_rooms=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'conf_rooms,attendant,call_queues,hunt_group,vm,fmfm,speed,sec_code,rec_all_calls,rec_calls,pconf,paging,mon_others,mon_block,mon,last_call,last_num,intercom,dnd,cid_block_single,cid_block,cid,cfwd,alarm,user_id,pbx_id' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'HASH(feature_status) is set for lmadsen2@lmentinc(1)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-feature_status:5] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|HASH(feature_status) is set for lmadsen2@lmentinc(1)") in new stack HASH(feature_status) is set for lmadsen2@lmentinc(1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Goto' -- Executing [s@sub-feature_status:6] Goto("SIP/lmadsen#lmentinc-0990d4f8", "return") in new stack -- Goto (sub-feature_status,s,7) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-feature_status:7] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:14] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-access_control|s|1(no_chk)") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG1' to 'no_chk' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '1:sub-stdexten|102|15' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-access_control:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-access_control start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:2] Set("SIP/lmadsen#lmentinc-0990d4f8", "FEATURE=no_chk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "NO_CHK=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/lmadsen#lmentinc - state 4 (Invalid) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-access_control:4] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-access_control:5] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Performing HASH(access_control) checks for lmadsen2@lmentinc(1)") in new stack Performing HASH(access_control) checks for lmadsen2@lmentinc(1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8838]: app_queue.c:546 changethread: Device 'SIP/lmadsen#lmentinc' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-access_control:6] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?error|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-access_control:7] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(access_control)=1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (pbx_id,user_id,alarm,cfwd,cid,cid_block,cid_block_single,dnd,intercom,last_num,last_call,mon,mon_block,mon_others,paging,pconf,rec_calls,rec_all_calls,sec_code,speed,fmfm,vm,hunt_group,call_queues,attendant,conf_rooms=1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pbx_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (user_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (alarm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cfwd=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cid_block_single=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (dnd=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (intercom=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_num=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (last_call=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_block=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (mon_others=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (paging=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pconf=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rec_all_calls=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (sec_code=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (speed=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (fmfm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (vm=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (hunt_group=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (call_queues=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (attendant=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (conf_rooms=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-access_control:8] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|HASH for access_control is now set") in new stack HASH for access_control is now set [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-access_control:9] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "skip") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-access_control:10] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?error|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Return()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-access_control:11] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Return()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:15] Set("SIP/lmadsen#lmentinc-0990d4f8", "E=102") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:16] Set("SIP/lmadsen#lmentinc-0990d4f8", "REQUESTED_CHANNEL=lmadsen2#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:17] Set("SIP/lmadsen#lmentinc-0990d4f8", "ORIGNUM=102") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:18] Set("SIP/lmadsen#lmentinc-0990d4f8", "INTERNAL=INTERNAL") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:19] Set("SIP/lmadsen#lmentinc-0990d4f8", "EXITCONTEXT=vm-exitcontext") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Set(DIAL_FLAGS=d)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [102@sub-stdexten:20] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Set(DIAL_FLAGS=d)") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:21] Set("SIP/lmadsen#lmentinc-0990d4f8", "PHONE_LAST_CALL(lmadsen2|lmentinc)=lmadsen#lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:22] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "post_last_call") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:23] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-monitoring|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|24' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-monitoring:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-monitoring start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-monitoring:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-monitoring,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-monitoring:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-monitoring:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CALL MONITORING MODULE ENABLED") in new stack CALL MONITORING MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-monitoring:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "MONITOR_BLOCK=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-monitoring:9] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8") in new stack Call monitoring BLOCKED on SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-monitoring:10] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-monitoring:11] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:24] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-call_recording|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|25' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-call_recording:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "call_recording GoSub") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-call_recording:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-call_recording,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-call_recording:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-call_recording:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CALL RECORDING MODULE ENABLED") in new stack CALL RECORDING MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-call_recording:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "TOUCH_MONITOR=miami8-1173368259.0") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-call_recording:9] Set("SIP/lmadsen#lmentinc-0990d4f8", "DYNAMIC_FEATURES=automon") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-call_recording:10] Set("SIP/lmadsen#lmentinc-0990d4f8", "RECORD_CALL=0") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'DISABLED' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-call_recording:11] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Recording all calls: DISABLED") in new stack Recording all calls: DISABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-call_recording:12] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-call_recording:13] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?record_call") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: func_strings.c:134 regex: FUNCTION REGEX ([wW])(d) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-call_recording:14] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Set(DIAL_FLAGS=dW)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-call_recording:15] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Set(DIAL_FLAGS=dW)") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-call_recording:16] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "record_call") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'DISABLED' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-call_recording:17] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Status of MixMonitor is: DISABLED") in new stack Status of MixMonitor is: DISABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-call_recording:18] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [s@sub-call_recording:19] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'dW' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:25] Set("SIP/lmadsen#lmentinc-0990d4f8", "DIAL_FLAGS=dW") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [102@sub-stdexten:26] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?skip_gc") in new stack -- Goto (sub-stdexten,102,27) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:27] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "skip_gc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [102@sub-stdexten:28] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?check_cid_in:skip_cid_check_in") in new stack -- Goto (sub-stdexten,102,29) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:29] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "check_cid_in") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:30] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Performing a callerID check for incoming call from SIP/lmadsen#lmentinc-0990d4f8 for lmadsen2@lmentinc(1)") in new stack Performing a callerID check for incoming call from SIP/lmadsen#lmentinc-0990d4f8 for lmadsen2@lmentinc(1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '"Leif Madsen (100)" ' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:31] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|---> SIP/lmadsen#lmentinc-0990d4f8 ... Going in with "Leif Madsen (100)" ") in new stack ---> SIP/lmadsen#lmentinc-0990d4f8 ... Going in with "Leif Madsen (100)" [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'INT' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:32] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-callerid|s|1(IN|INT|lmadsen#lmentinc)") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG1' to 'IN' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG2' to 'INT' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG3' to 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '3:sub-stdexten|102|33' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-callerid:1] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CallerID Control Macro") in new stack CallerID Control Macro [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-callerid:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?nocallid|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-callerid:3] Set("SIP/lmadsen#lmentinc-0990d4f8", "CID_USERNAME=lmadsen") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-callerid:4] Set("SIP/lmadsen#lmentinc-0990d4f8", "CID_PBX=lmentinc") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'LM Enterprises,4166289921,100,1,1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-callerid:5] Set("SIP/lmadsen#lmentinc-0990d4f8", "HASH(CIDCONTROL)=LM Enterprises,4166289921,100,1,1") in new stack [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:133 array: array (cidname,cidnumber,pbx_extension,rewrite_caller_id,sip_restrictcid=LM Enterprises,4166289921,100,1,1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cidname=LM Enterprises) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (cidnumber=4166289921) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (pbx_extension=100) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (rewrite_caller_id=1) [Mar 8 10:37:39] DEBUG[8837]: func_odbc.c:145 array: array set value (sip_restrictcid=1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-callerid:6] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?in|1") in new stack -- Goto (sub-callerid,in,1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '"Leif Madsen (100)" ' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [in@sub-callerid:1] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Starting with "Leif Madsen (100)" --> lmadsen#lmentinc") in new stack Starting with "Leif Madsen (100)" --> lmadsen#lmentinc [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [in@sub-callerid:2] Set("SIP/lmadsen#lmentinc-0990d4f8", "REVISIT=in") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [in@sub-callerid:3] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Checking if were a NULL CID") in new stack Checking if were a NULL CID [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Leif Madsen (100)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [in@sub-callerid:4] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?nocallid|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [in@sub-callerid:5] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?nocallid|1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [in@sub-callerid:6] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Not NULL| Saving...") in new stack Not NULL| Saving... [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'LM Enterprises' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Leif Madsen (100)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'LM Enterprises' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [in@sub-callerid:7] Set("SIP/lmadsen#lmentinc-0990d4f8", "CIDNAME=LM Enterprises") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '100' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '4166289921' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '100' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen#lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '100' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [in@sub-callerid:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "CIDNUM=100") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [in@sub-callerid:9] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "1?:exit") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [in@sub-callerid:10] Set("SIP/lmadsen#lmentinc-0990d4f8", "CALLERID(name)=LM Enterprises") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [in@sub-callerid:11] Set("SIP/lmadsen#lmentinc-0990d4f8", "CALLERID(num)=100") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [in@sub-callerid:12] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|...CID saved: name LM Enterprises and number 100") in new stack ...CID saved: name LM Enterprises and number 100 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '"LM Enterprises" <100>' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [in@sub-callerid:13] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|Leaving CallerID Macro w/ "LM Enterprises" <100>") in new stack Leaving CallerID Macro w/ "LM Enterprises" <100> [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Return' -- Executing [in@sub-callerid:14] Return("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '"LM Enterprises" <100>' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:33] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|<--- SIP/lmadsen#lmentinc-0990d4f8 ... Coming back with "LM Enterprises" <100>") in new stack <--- SIP/lmadsen#lmentinc-0990d4f8 ... Coming back with "LM Enterprises" <100> [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'LM Enterprises' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '100' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:34] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|No CID check required. Using NAME: LM Enterprises NUMBER: 100") in new stack No CID check required. Using NAME: LM Enterprises NUMBER: 100 [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:35] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-dnd|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|36' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-dnd:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-dnd start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-dnd:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-dnd,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-dnd:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-dnd:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|DO NOT DISTURB MODULE ENABLED") in new stack DO NOT DISTURB MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-dnd:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "dnd=0") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'DISABLED' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-dnd:9] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|DND: DISABLED") in new stack DND: DISABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Return()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-dnd:10] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Return()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [102@sub-stdexten:36] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:37] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-callforwarding|s|1(PRE)") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:174 gosub_exec: Setting 'ARG1' to 'PRE' [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '1:sub-stdexten|102|38' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-callforwarding:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-callforwarding start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-callforwarding:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-callforwarding,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-callforwarding:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-callforwarding:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CALL FORWARDING MODULE ENABLED") in new stack CALL FORWARDING MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-callforwarding:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "TYPE=PRE") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-callforwarding:9] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'DISABLED' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-callforwarding:10] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|CFWD: DISABLED") in new stack CFWD: DISABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-callforwarding:11] Set("SIP/lmadsen#lmentinc-0990d4f8", "FWD_ACTIVE=0") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Return(continue)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-callforwarding:12] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Return(continue)") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'NoOp()' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [102@sub-stdexten:38] Exec("SIP/lmadsen#lmentinc-0990d4f8", "NoOp()") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Verbose(1|OK -- No need to call forward at this time)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [102@sub-stdexten:39] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Verbose(1|OK -- No need to call forward at this time)") in new stack OK -- No need to call forward at this time [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [102@sub-stdexten:40] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?vm_chk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Gosub' -- Executing [102@sub-stdexten:41] Gosub("SIP/lmadsen#lmentinc-0990d4f8", "sub-findfollow|s|1()") in new stack [Mar 8 10:37:39] DEBUG[8837]: app_stack.c:179 gosub_exec: Setting gosub return address to '0:sub-stdexten|102|42' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-findfollow:1] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "sub-findfollow start") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@sub-findfollow:2] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?module_off:module_on") in new stack -- Goto (sub-findfollow,s,6) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [s@sub-findfollow:6] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "module_on") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [s@sub-findfollow:7] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|FIND ME FOLLOW ME MODULE ENABLED") in new stack FIND ME FOLLOW ME MODULE ENABLED [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [s@sub-findfollow:8] Set("SIP/lmadsen#lmentinc-0990d4f8", "FMFM=0") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Return(continue)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [s@sub-findfollow:9] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Return(continue)") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '1' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'Verbose(1|OK -- No need to perform FindMeFollowMe at this time)' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Exec' -- Executing [102@sub-stdexten:42] Exec("SIP/lmadsen#lmentinc-0990d4f8", "Verbose(1|OK -- No need to perform FindMeFollowMe at this time)") in new stack OK -- No need to perform FindMeFollowMe at this time [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'GotoIf' -- Executing [102@sub-stdexten:43] GotoIf("SIP/lmadsen#lmentinc-0990d4f8", "0?vm_chk") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:5969 pbx_builtin_gotoif: Not taking any branch [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:44] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "chanisavail") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'NoOp' -- Executing [102@sub-stdexten:45] NoOp("SIP/lmadsen#lmentinc-0990d4f8", "call") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmadsen2' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is 'lmentinc' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '24' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:46] Set("SIP/lmadsen#lmentinc-0990d4f8", "RING_CYCLE=24") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Set' -- Executing [102@sub-stdexten:47] Set("SIP/lmadsen#lmentinc-0990d4f8", "DIAL_ATTEMPT_FLAG=1") in new stack [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [102@sub-stdexten:48] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|STATUS: Delivering 102(SIP/lmadsen#lmentinc-0990d4f8) to DEVICE: lmadsen2@lmentinc(1)") in new stack STATUS: Delivering 102(SIP/lmadsen#lmentinc-0990d4f8) to DEVICE: lmadsen2@lmentinc(1) [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1712 pbx_substitute_variables_helper_full: Expression result is '0' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1644 pbx_substitute_variables_helper_full: Function result is '24' [Mar 8 10:37:39] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Dial' -- Executing [102@sub-stdexten:49] Dial("SIP/lmadsen#lmentinc-0990d4f8", "SIP/openser/lmadsen2#lmentinc|24|dW") in new stack [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:15267 sip_request_call: Asked to create a SIP channel with formats: 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:4308 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:2619 create_addr_from_peer: Our T38 capability (3856) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:2573 do_setnat: Setting NAT on RTP to Off [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:2583 do_setnat: Setting NAT on UDPTL to Off [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3803 sip_new: *** Our native formats are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3804 sip_new: *** Joint capabilities are 0x0 (nothing) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3805 sip_new: *** Our capabilities are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3806 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3808 sip_new: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3829 sip_new: This channel will not be able to handle video. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-49. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-48. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable DIAL_ATTEMPT_FLAG. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-47. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable RING_CYCLE. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-46. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~ODBCFIELDS~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-45. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-44. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-43. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-42. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable GOSUB_RETVAL. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable FMFM. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-findfollow-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-41. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-40. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-39. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-38. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-12. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable FWD_ACTIVE. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable TYPE. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callforwarding-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-37. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-36. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable dnd. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-dnd-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-35. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-34. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-33. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-14. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-13. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-12. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable CIDNUM. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable CIDNAME. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable REVISIT. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-in-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~CIDCONTROL~sip_restrictcid~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~CIDCONTROL~rewrite_caller_id~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~CIDCONTROL~pbx_extension~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~CIDCONTROL~cidnumber~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~CIDCONTROL~cidname~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable CID_PBX. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable CID_USERNAME. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-callerid-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-32. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-31. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-30. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-29. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-28. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-27. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-26. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable DIAL_FLAGS. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-25. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-19. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-18. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-17. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-16. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-15. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-14. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-13. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-12. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable RECORD_CALL. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable DYNAMIC_FEATURES. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable TOUCH_MONITOR. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-call_recording-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-24. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable MONITOR_BLOCK. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-monitoring-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-23. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-22. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ODBCROWS. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-21. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-20. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable EXITCONTEXT. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-19. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable INTERNAL. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-18. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ORIGNUM. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-17. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable REQUESTED_CHANNEL. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-16. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable E. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-15. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~conf_rooms~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~attendant~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~call_queues~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~hunt_group~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~vm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~fmfm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~speed~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~sec_code~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~rec_all_calls~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~rec_calls~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~pconf~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~paging~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~mon_others~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~mon_block~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~mon~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~last_call~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~last_num~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~intercom~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~dnd~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~cid_block_single~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~cid_block~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~cid~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~cfwd~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~alarm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~user_id~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~access_control~pbx_id~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable NO_CHK. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable FEATURE. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-access_control-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-14. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~conf_rooms~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~attendant~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~call_queues~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~hunt_group~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~vm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~fmfm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~speed~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~sec_code~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~rec_all_calls~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~rec_calls~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~pconf~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~paging~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~mon_others~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~mon_block~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~mon~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~last_call~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~last_num~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~intercom~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~dnd~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~cid_block_single~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~cid_block~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~cid~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~cfwd~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~alarm~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~user_id~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~feature_status~pbx_id~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-feature_status-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-13. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~USER_TRUNKS~max_calls~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable GROUP_lmentinc. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable GROUP_pbx. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-maxcallcheck-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-12. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3296 ast_channel_inherit_variables: Copying hard-transferable variable USER_ID. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3296 ast_channel_inherit_variables: Copying hard-transferable variable PBX. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3296 ast_channel_inherit_variables: Copying hard-transferable variable USERNAME. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable PHONE_EXTEN. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ORIG_PBX. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ORIG_USERNAME. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable DEVICE_FLAG. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable TMP. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-stdexten-102-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~GOSUB~STACK~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ARG1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-13. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-12. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-11. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~EXT_STATUS~status~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable ~HASH~EXT_INFO~user_name~. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-int-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-10. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-9. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-8. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-7. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-6. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-sub-who-s-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-5. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-4. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-3. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-2. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable STACK-origination-102-1. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable MEMBER_ORIGINATED. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Mar 8 10:37:39] DEBUG[8837]: channel.c:3301 ast_channel_inherit_variables: Not copying variable SIPURI. [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:2828 sip_call: Outgoing Call for lmadsen2#lmentinc [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:3001 update_call_counter: Updating call counter for outgoing call [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:2843 sip_call: Our T38 capability (3856), joint T38 capability (3856) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:6182 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: False [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:6183 add_sdp: ** Our prefcodec: 0x4 (ulaw) [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:6314 add_sdp: -- Done with adding codecs to SDP [Mar 8 10:37:39] DEBUG[8837]: chan_sip.c:6359 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) -- Called openser/lmadsen2#lmentinc [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:2129 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' Request 102: Found [Mar 8 10:37:39] DEBUG[8820]: chan_sip.c:11621 handle_response_invite: SIP response 100 to standard invite [Mar 8 10:37:40] DEBUG[8820]: chan_sip.c:2129 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' Request 102: Found [Mar 8 10:37:40] DEBUG[8820]: chan_sip.c:11621 handle_response_invite: SIP response 180 to standard invite [Mar 8 10:37:40] DEBUG[8820]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/openser-09924110 [Mar 8 10:37:40] DEBUG[8810]: devicestate.c:161 ast_device_state: -- No provider found, checking channel drivers for SIP - openser SIP/openser-09924110 is ringing [Mar 8 10:37:40] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer openser [Mar 8 10:37:40] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/openser - state 1 (Not in use) [Mar 8 10:37:40] DEBUG[8839]: app_queue.c:546 changethread: Device 'SIP/openser' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:2069 __sip_ack: Acked pending invite 102 [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' of Request 102: Match Not Found [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:11621 handle_response_invite: SIP response 200 to standard invite [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:5123 process_sdp: T38 state changed to 0 on channel SIP/openser-09924110 [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:5203 process_sdp: We're settling with these formats: 0x4 (ulaw) [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:5210 process_sdp: We have an owner, now see if we need to change this call [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:3001 update_call_counter: Updating call counter for outgoing call [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:7935 build_route: build_route: Record-Route hop: -- Call on SIP/openser-09924110 left from hold [Mar 8 10:37:41] DEBUG[8837]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/openser-09924110 -- SIP/openser-09924110 answered SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:41] DEBUG[8837]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:3461 sip_answer: SIP answering channel: SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:6414 transmit_response_with_sdp: Setting framing from config on incoming call [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:6182 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: True [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:6183 add_sdp: ** Our prefcodec: 0x0 (nothing) [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:6314 add_sdp: -- Done with adding codecs to SDP [Mar 8 10:37:41] DEBUG[8837]: chan_sip.c:6359 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 8 10:37:41] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - openser [Mar 8 10:37:41] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer openser [Mar 8 10:37:41] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/openser - state 1 (Not in use) [Mar 8 10:37:41] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:41] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:41] DEBUG[8840]: app_queue.c:546 changethread: Device 'SIP/openser' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 8 10:37:41] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/lmadsen#lmentinc - state 4 (Invalid) [Mar 8 10:37:41] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:41] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:41] DEBUG[8841]: app_queue.c:546 changethread: Device 'SIP/lmadsen#lmentinc' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. -- fixed jitterbuffer created on channel SIP/openser-09924110 [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 8 10:37:41] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on 'f5b30000-389e25e4@switch1-mia.mixnetworks.net' of Response 1933693309: Match Not Found -- fixed jitterbuffer created on channel SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:41] DEBUG[8837]: rtp.c:2670 ast_rtp_write: Ooh, format changed from unknown to ulaw [Mar 8 10:37:41] DEBUG[8837]: rtp.c:2687 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [Mar 8 10:37:42] DEBUG[8837]: rtp.c:2670 ast_rtp_write: Ooh, format changed from unknown to ulaw [Mar 8 10:37:42] DEBUG[8837]: rtp.c:2687 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:5123 process_sdp: T38 state changed to 0 on channel SIP/openser-09924110 [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:5203 process_sdp: We're settling with these formats: 0x4 (ulaw) [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:5210 process_sdp: We have an owner, now see if we need to change this call [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:13526 handle_request_invite: SIP/openser-09924110: This call is UP.... [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:6414 transmit_response_with_sdp: Setting framing from config on incoming call [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:6182 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: True [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:6183 add_sdp: ** Our prefcodec: 0x4 (ulaw) [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:6314 add_sdp: -- Done with adding codecs to SDP [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:6359 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) -- Started music on hold, class 'default', on SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:43] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 160 sample intervals [Mar 8 10:37:43] DEBUG[8837]: channel.c:2332 __ast_read: Generator got voice, switching to phase locked mode [Mar 8 10:37:43] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:43] DEBUG[8837]: res_musiconhold.c:254 ast_moh_files_next: SIP/lmadsen#lmentinc-0990d4f8 Opened file 1 '/var/lib/asterisk/moh/fpm-world-mix' [Mar 8 10:37:43] DEBUG[8837]: rtp.c:2540 ast_rtp_raw_write: Difference is 1560, ms is 215 [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received ACK (6) - Command in SIP ACK [Mar 8 10:37:43] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' of Response 101: Match Not Found [Mar 8 10:37:48] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received REFER (9) - Command in SIP REFER [Mar 8 10:37:48] DEBUG[8820]: chan_sip.c:13985 handle_request_refer: chan1->name: SIP/openser-09924110 -- Stopped music on hold on SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:48] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:48] DEBUG[8820]: channel.c:1480 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:48] DEBUG[8820]: chan_sip.c:14048 handle_request_refer: Blind transfer succeeded. Telling transferer. [Mar 8 10:37:48] DEBUG[8837]: channel.c:3800 ast_generic_bridge: Didn't get a frame from channel: SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:48] DEBUG[8837]: channel.c:4118 ast_channel_bridge: Bridge stops bridging channels SIP/lmadsen#lmentinc-0990d4f8 and SIP/openser-09924110 [Mar 8 10:37:48] DEBUG[8837]: channel.c:1693 ast_hangup: Hanging up channel 'SIP/openser-09924110' [Mar 8 10:37:48] DEBUG[8837]: chan_sip.c:3295 sip_hangup: SIP Transfer: Not hanging up right now... Rescheduling hangup for 2362bca348a1a62332dcf5110e0e4c60@64.251.19.145. -- fixed jitterbuffer destroyed on channel SIP/openser-09924110 [Mar 8 10:37:48] DEBUG[8837]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/openser-09924110 [Mar 8 10:37:48] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - openser [Mar 8 10:37:48] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer openser [Mar 8 10:37:48] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/openser - state 1 (Not in use) [Mar 8 10:37:48] DEBUG[8837]: rtp.c:1474 ast_rtp_early_bridge: Channel '' has no RTP, not doing anything [Mar 8 10:37:48] DEBUG[8837]: app_dial.c:1670 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Mar 8 10:37:48] DEBUG[8842]: app_queue.c:546 changethread: Device 'SIP/openser' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Mar 8 10:37:48] DEBUG[8837]: pbx.c:2389 __ast_pbx_run: Spawn extension (default,101,0) exited non-zero on 'SIP/lmadsen#lmentinc-0990d4f8' == Spawn extension (default, 101, 0) exited non-zero on 'SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:48] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Verbose' -- Executing [101@default:1] Verbose("SIP/lmadsen#lmentinc-0990d4f8", "1|SIP/lmadsen#lmentinc-0990d4f8 hit DEFAULT CONTEXT looking for 101") in new stack SIP/lmadsen#lmentinc-0990d4f8 hit DEFAULT CONTEXT looking for 101 [Mar 8 10:37:48] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Playback' -- Executing [101@default:2] Playback("SIP/lmadsen#lmentinc-0990d4f8", "silence/2&no-route-exists-to-dest") in new stack [Mar 8 10:37:48] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 160 sample intervals -- Playing 'silence/2' (language 'en') [Mar 8 10:37:48] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' of Request 103: Match Not Found [Mar 8 10:37:49] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on '2362bca348a1a62332dcf5110e0e4c60@64.251.19.145' of Request 104: Match Not Found [Mar 8 10:37:49] DEBUG[8820]: chan_sip.c:14590 handle_request: **** Received BYE (8) - Command in SIP BYE [Mar 8 10:37:49] DEBUG[8820]: chan_sip.c:1631 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 2362bca348a1a62332dcf5110e0e4c60@64.251.19.145 [Mar 8 10:37:49] DEBUG[8820]: chan_sip.c:14171 handle_request_bye: Received bye, no owner, selfdestruct soon. [Mar 8 10:37:50] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:50] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:50] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 160 sample intervals -- Playing 'no-route-exists-to-dest' (language 'en') [Mar 8 10:37:53] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:53] DEBUG[8837]: channel.c:1997 ast_settimeout: Scheduling timer at 0 sample intervals [Mar 8 10:37:53] DEBUG[8837]: pbx.c:1791 pbx_extension_helper: Launching 'Hangup' -- Executing [101@default:3] Hangup("SIP/lmadsen#lmentinc-0990d4f8", "") in new stack [Mar 8 10:37:53] DEBUG[8837]: pbx.c:2389 __ast_pbx_run: Spawn extension (default,101,3) exited non-zero on 'SIP/lmadsen#lmentinc-0990d4f8' == Spawn extension (default, 101, 3) exited non-zero on 'SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:53] DEBUG[8837]: channel.c:1693 ast_hangup: Hanging up channel 'SIP/lmadsen#lmentinc-0990d4f8' [Mar 8 10:37:53] DEBUG[8837]: chan_sip.c:3310 sip_hangup: Hangup call SIP/lmadsen#lmentinc-0990d4f8, SIP callid f5b30000-389e25e4@switch1-mia.mixnetworks.net) -- fixed jitterbuffer destroyed on channel SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:53] DEBUG[8837]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/lmadsen#lmentinc-0990d4f8 [Mar 8 10:37:53] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:53] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:53] DEBUG[8810]: devicestate.c:287 do_state_change: Changing state for SIP/lmadsen#lmentinc - state 4 (Invalid) [Mar 8 10:37:53] DEBUG[8810]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - lmadsen#lmentinc [Mar 8 10:37:53] DEBUG[8810]: chan_sip.c:15201 sip_devicestate: Checking device state for peer lmadsen#lmentinc [Mar 8 10:37:53] DEBUG[8843]: app_queue.c:546 changethread: Device 'SIP/lmadsen#lmentinc' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:2087 __sip_ack: Stopping retransmission on 'f5b30000-389e25e4@switch1-mia.mixnetworks.net' of Request 102: Match Not Found Really destroying SIP dialog 'f5b30000-389e25e4@switch1-mia.mixnetworks.net' Method: ACK [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10706 sip_dump_history: ---------- SIP HISTORY for 'f5b30000-389e25e4@switch1-mia.mixnetworks.net' [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10710 sip_dump_history: * SIP Call [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 001. Rx INVITE / 1933693309 INVITE / sip:102@switch1-mia.mixnetworks.ne [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 002. NewChan Channel SIP/lmadsen#lmentinc-0990d4f8 - from f5b30000-389e25e4@ [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 003. TxResp SIP/2.0 / 1933693309 INVITE - 100 Trying [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 004. TxResp SIP/2.0 / 1933693309 INVITE - 180 Ringing [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 005. TxRespRel SIP/2.0 / 1933693309 INVITE - 200 OK [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 006. Rx ACK / 1933693309 ACK / sip:102@64.251.19.145 [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 007. SchedDestroy 32000 ms [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 008. TxReqRel BYE / 102 BYE - -UNKNOWN- [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 009. RTCPaudio Quality:ssrc=355405998;themssrc=2018462460;lp=0;rxjitter=0.0007 [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10713 sip_dump_history: 010. Rx SIP/2.0 / 102 BYE / 200 OK [Mar 8 10:37:54] DEBUG[8820]: chan_sip.c:10716 sip_dump_history: ---------- END SIP HISTORY for 'f5b30000-389e25e4@switch1-mia.mixnetworks.net' *CLI> stop now Beginning asterisk shutdown.... Executing last minute cleanups == Destroying musiconhold processes Asterisk cleanly ending (0). [Mar 8 10:37:56] DEBUG[8805]: asterisk.c:1276 quit_handler: Asterisk ending (0).