Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf [Dec 1 23:09:34] == Parsing '/etc/asterisk/asterisk.conf': [Dec 1 23:09:34] Found [Dec 1 23:09:34] == Parsing '/etc/asterisk/extconfig.conf': [Dec 1 23:09:34] Found [Dec 1 23:09:34] Asterisk 1.2.13, Copyright (C) 1999 - 2006 Digium, Inc. and others. [Dec 1 23:09:34] Created by Mark Spencer [Dec 1 23:09:34] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. [Dec 1 23:09:34] This is free software, with components licensed under the GNU General Public [Dec 1 23:09:34] License version 2 and other licenses; you are welcome to redistribute it under [Dec 1 23:09:34] certain conditions. Type 'show license' for details. [Dec 1 23:09:34] ========================================================================= [Dec 1 23:09:34] == Parsing '/etc/asterisk/logger.conf': Parsing /etc/asterisk/logger.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] Asterisk Event Logger Started /var/log/asterisk/event_log [Dec 1 23:09:34] Asterisk Dynamic Loader loading preload modules: [Dec 1 23:09:34] == Parsing '/etc/asterisk/modules.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/modules.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == Manager registered action Ping [Dec 1 23:09:34] == Manager registered action Events [Dec 1 23:09:34] == Manager registered action Logoff [Dec 1 23:09:34] == Manager registered action Hangup [Dec 1 23:09:34] == Manager registered action Status [Dec 1 23:09:34] == Manager registered action Setvar [Dec 1 23:09:34] == Manager registered action Getvar [Dec 1 23:09:34] == Manager registered action Redirect [Dec 1 23:09:34] == Manager registered action Originate [Dec 1 23:09:34] == Manager registered action Command [Dec 1 23:09:34] == Manager registered action ExtensionState [Dec 1 23:09:34] == Manager registered action AbsoluteTimeout [Dec 1 23:09:34] == Manager registered action MailboxStatus [Dec 1 23:09:34] == Manager registered action MailboxCount [Dec 1 23:09:34] == Manager registered action ListCommands [Dec 1 23:09:34] == Parsing '/etc/asterisk/manager.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/manager.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] Asterisk Management interface listening on port 5038 [Dec 1 23:09:34] NOTICE[13898]: cdr.c:1192 do_reload: CDR simple logging enabled. [Dec 1 23:09:34] == Parsing '/etc/asterisk/rtp.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/rtp.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == RTP Allocating from port range 10000 -> 20000 [Dec 1 23:09:34] Asterisk PBX Core Initializing [Dec 1 23:09:34] Registering builtin applications: [Dec 1 23:09:34] [AbsoluteTimeout] [Dec 1 23:09:34] == Registered application 'AbsoluteTimeout' [Dec 1 23:09:34] [Answer] [Dec 1 23:09:34] == Registered application 'Answer' [Dec 1 23:09:34] [BackGround] [Dec 1 23:09:34] == Registered application 'BackGround' [Dec 1 23:09:34] [Busy] [Dec 1 23:09:34] == Registered application 'Busy' [Dec 1 23:09:34] [Congestion] [Dec 1 23:09:34] == Registered application 'Congestion' [Dec 1 23:09:34] [DigitTimeout] [Dec 1 23:09:34] == Registered application 'DigitTimeout' [Dec 1 23:09:34] [Goto] [Dec 1 23:09:34] == Registered application 'Goto' [Dec 1 23:09:34] [GotoIf] [Dec 1 23:09:34] == Registered application 'GotoIf' [Dec 1 23:09:34] [GotoIfTime] [Dec 1 23:09:34] == Registered application 'GotoIfTime' [Dec 1 23:09:34] [ExecIfTime] [Dec 1 23:09:34] == Registered application 'ExecIfTime' [Dec 1 23:09:34] [Hangup] [Dec 1 23:09:34] == Registered application 'Hangup' [Dec 1 23:09:34] [NoOp] [Dec 1 23:09:34] == Registered application 'NoOp' [Dec 1 23:09:34] [Progress] [Dec 1 23:09:34] == Registered application 'Progress' [Dec 1 23:09:34] [ResetCDR] [Dec 1 23:09:34] == Registered application 'ResetCDR' [Dec 1 23:09:34] [ResponseTimeout] [Dec 1 23:09:34] == Registered application 'ResponseTimeout' [Dec 1 23:09:34] [Ringing] [Dec 1 23:09:34] == Registered application 'Ringing' [Dec 1 23:09:34] [SayNumber] [Dec 1 23:09:34] == Registered application 'SayNumber' [Dec 1 23:09:34] [SayDigits] [Dec 1 23:09:34] == Registered application 'SayDigits' [Dec 1 23:09:34] [SayAlpha] [Dec 1 23:09:34] == Registered application 'SayAlpha' [Dec 1 23:09:34] [SayPhonetic] [Dec 1 23:09:34] == Registered application 'SayPhonetic' [Dec 1 23:09:34] [SetAccount] [Dec 1 23:09:34] == Registered application 'SetAccount' [Dec 1 23:09:34] [SetAMAFlags] [Dec 1 23:09:34] == Registered application 'SetAMAFlags' [Dec 1 23:09:34] [SetGlobalVar] [Dec 1 23:09:34] == Registered application 'SetGlobalVar' [Dec 1 23:09:34] [SetLanguage] [Dec 1 23:09:34] == Registered application 'SetLanguage' [Dec 1 23:09:34] [Set] [Dec 1 23:09:34] == Registered application 'Set' [Dec 1 23:09:34] [SetVar] [Dec 1 23:09:34] == Registered application 'SetVar' [Dec 1 23:09:34] [ImportVar] [Dec 1 23:09:34] == Registered application 'ImportVar' [Dec 1 23:09:34] [Wait] [Dec 1 23:09:34] == Registered application 'Wait' [Dec 1 23:09:34] [WaitExten] [Dec 1 23:09:34] == Registered application 'WaitExten' [Dec 1 23:09:34] == Manager registered action DBGet [Dec 1 23:09:34] == Manager registered action DBPut [Dec 1 23:09:34] == Parsing '/etc/asterisk/enum.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/enum.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] Asterisk Dynamic Loader Starting: [Dec 1 23:09:34] == Parsing '/etc/asterisk/modules.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/modules.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] [res_musiconhold.so][Dec 1 23:09:34] => (Music On Hold Resource) [Dec 1 23:09:34] == Registered application 'MusicOnHold' [Dec 1 23:09:34] == Registered application 'WaitMusicOnHold' [Dec 1 23:09:34] == Registered application 'SetMusicOnHold' [Dec 1 23:09:34] == Registered application 'StartMusicOnHold' [Dec 1 23:09:34] == Registered application 'StopMusicOnHold' [Dec 1 23:09:34] == Parsing '/etc/asterisk/musiconhold.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/musiconhold.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] WARNING[13898]: res_musiconhold.c:1017 load_moh_classes: The old musiconhold.conf syntax has been deprecated! Please refer to the sample configuration for information on the new syntax. [Dec 1 23:09:34] [chan_capi.so][Dec 1 23:09:34] => (Common ISDN API for Asterisk) [Dec 1 23:09:34] == Parsing '/etc/asterisk/capi.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/capi.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == This box has 1 capi controller(s). [Dec 1 23:09:34] -- CAPI/contr1 supports DTMF [Dec 1 23:09:34] -- CAPI/contr1 supports supplementary services [Dec 1 23:09:34] > supplementary services : 0x000003ff [Dec 1 23:09:34] > HOLD/RETRIEVE [Dec 1 23:09:34] > TERMINAL PORTABILITY [Dec 1 23:09:34] > ECT [Dec 1 23:09:34] > 3PTY [Dec 1 23:09:34] > CF [Dec 1 23:09:34] > CD [Dec 1 23:09:34] > MCID [Dec 1 23:09:34] > CCBS [Dec 1 23:09:34] > MWI [Dec 1 23:09:34] > CCNR [Dec 1 23:09:34] == Reading config for ISDN1 [Dec 1 23:09:34] -- capi_pvt ISDN1-pseudo-D (669678,capi-in,0,2) (0,4,64) [Dec 1 23:09:34] -- capi_pvt ISDN1 (669678,capi-in,0,2) (0,4,64) [Dec 1 23:09:34] -- capi_pvt ISDN1 (669678,capi-in,0,2) (0,4,64) [Dec 1 23:09:34] -- listening on contr1 CIPmask = 0x1fff03ff [Dec 1 23:09:34] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'CAPI' (Common ISDN API Driver (cm-0.6.4) ) [Dec 1 23:09:34] == Registered channel type 'CAPI' (Common ISDN API Driver (cm-0.6.4) ) [Dec 1 23:09:34] == Registered application 'capiCommand' [Dec 1 23:09:34] == Registered custom function VANITYNUMBER [Dec 1 23:09:34] [res_agi.so][Dec 1 23:09:34] => (Asterisk Gateway Interface (AGI)) [Dec 1 23:09:34] == Registered application 'DeadAGI' [Dec 1 23:09:34] == Registered application 'EAGI' [Dec 1 23:09:34] == Registered application 'AGI' [Dec 1 23:09:34] [res_features.so][Dec 1 23:09:34] => (Call Features Resource) [Dec 1 23:09:34] == Parsing '/etc/asterisk/features.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/features.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == Registered Feature 'testconf' [Dec 1 23:09:34] == Mapping Feature 'testconf' to app 'agi' with code '*9' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'parkedcalls' [Dec 1 23:09:34] -- Registered extension context 'parkedcalls' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '700' priority 1 to parkedcalls [Dec 1 23:09:34] -- Added extension '700' priority 1 to parkedcalls [Dec 1 23:09:34] == Registered application 'ParkedCall' [Dec 1 23:09:34] == Registered application 'Park' [Dec 1 23:09:34] == Manager registered action ParkedCalls [Dec 1 23:09:34] [res_crypto.so][Dec 1 23:09:34] => (Cryptographic Digital Signatures) [Dec 1 23:09:34] -- Loaded PUBLIC key 'iaxtel' [Dec 1 23:09:34] DEBUG[13898]: res_crypto.c:257 try_load_key: Key 'iaxtel' loaded OK [Dec 1 23:09:34] -- Loaded PUBLIC key 'freeworlddialup' [Dec 1 23:09:34] DEBUG[13898]: res_crypto.c:257 try_load_key: Key 'freeworlddialup' loaded OK [Dec 1 23:09:34] [res_indications.so][Dec 1 23:09:34] => (Indications Configuration) [Dec 1 23:09:34] == Parsing '/etc/asterisk/indications.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/indications.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] -- Registered indication country 'cl' [Dec 1 23:09:34] -- Registered indication country 'tw' [Dec 1 23:09:34] -- Registered indication country 'us' [Dec 1 23:09:34] -- Registered indication country 'au' [Dec 1 23:09:34] -- Registered indication country 'fr' [Dec 1 23:09:34] -- Registered indication country 'de' [Dec 1 23:09:34] -- Registered indication country 'nl' [Dec 1 23:09:34] -- Registered indication country 'uk' [Dec 1 23:09:34] -- Registered indication country 'fi' [Dec 1 23:09:34] -- Registered indication country 'no' [Dec 1 23:09:34] -- Registered indication country 'br' [Dec 1 23:09:34] -- Registered indication country 'za' [Dec 1 23:09:34] -- Registered indication country 'it' [Dec 1 23:09:34] -- Registered indication country 'us-o' [Dec 1 23:09:34] -- Registered indication country 'gr' [Dec 1 23:09:34] -- Registered indication country 'ru' [Dec 1 23:09:34] -- Registered indication country 'nz' [Dec 1 23:09:34] -- Setting default indication country to 'de' [Dec 1 23:09:34] == Registered application 'PlayTones' [Dec 1 23:09:34] == Registered application 'StopPlayTones' [Dec 1 23:09:34] [res_adsi.so][Dec 1 23:09:34] => (ADSI Resource) [Dec 1 23:09:34] == Parsing '/etc/asterisk/adsi.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/adsi.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] [res_monitor.so][Dec 1 23:09:34] => (Call Monitoring Resource) [Dec 1 23:09:34] == Registered application 'Monitor' [Dec 1 23:09:34] == Registered application 'StopMonitor' [Dec 1 23:09:34] == Registered application 'ChangeMonitor' [Dec 1 23:09:34] == Manager registered action Monitor [Dec 1 23:09:34] == Manager registered action StopMonitor [Dec 1 23:09:34] == Manager registered action ChangeMonitor [Dec 1 23:09:34] [pbx_functions.so][Dec 1 23:09:34] => (Builtin dialplan functions) [Dec 1 23:09:34] == Registered custom function MD5 [Dec 1 23:09:34] == Registered custom function CHECK_MD5 [Dec 1 23:09:34] == Registered custom function MATH [Dec 1 23:09:34] == Registered custom function GROUP_COUNT [Dec 1 23:09:34] == Registered custom function GROUP_MATCH_COUNT [Dec 1 23:09:34] == Registered custom function GROUP [Dec 1 23:09:34] == Registered custom function GROUP_LIST [Dec 1 23:09:34] == Registered custom function FIELDQTY [Dec 1 23:09:34] == Registered custom function REGEX [Dec 1 23:09:34] == Registered custom function LEN [Dec 1 23:09:34] == Registered custom function STRFTIME [Dec 1 23:09:34] == Registered custom function EVAL [Dec 1 23:09:34] == Registered custom function CDR [Dec 1 23:09:34] == Registered custom function ISNULL [Dec 1 23:09:34] == Registered custom function SET [Dec 1 23:09:34] == Registered custom function EXISTS [Dec 1 23:09:34] == Registered custom function IF [Dec 1 23:09:34] == Registered custom function IFTIME [Dec 1 23:09:34] == Registered custom function ENV [Dec 1 23:09:34] == Registered custom function DB [Dec 1 23:09:34] == Registered custom function DB_EXISTS [Dec 1 23:09:34] == Registered custom function TIMEOUT [Dec 1 23:09:34] == Registered custom function LANGUAGE [Dec 1 23:09:34] == Registered custom function MUSICCLASS [Dec 1 23:09:34] [pbx_config.so][Dec 1 23:09:34] => (Text Extension Configuration) [Dec 1 23:09:34] == Parsing '/etc/asterisk/extensions.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/extensions.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == Setting global variable 'CONSOLE' to 'Console/dsp' [Dec 1 23:09:34] == Setting global variable 'IAXINFO' to 'guest' [Dec 1 23:09:34] == Setting global variable 'TRUNK' to 'CAPI' [Dec 1 23:09:34] == Setting global variable 'TRUNKMSD' to '1' [Dec 1 23:09:34] == Setting global variable 'FWDNUMBER' to '643203' [Dec 1 23:09:34] == Setting global variable 'FWDCIDNAME' to 'wolfgang' [Dec 1 23:09:34] == Setting global variable 'FWDPASSWORD' to 'porzellan' [Dec 1 23:09:34] == Setting global variable 'FWDRINGS' to 'IAX2/wolfgang' [Dec 1 23:09:34] == Setting global variable 'DYNAMIC_FEATURES' to 'testconf' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'macro-stdexten' [Dec 1 23:09:34] -- Registered extension context 'macro-stdexten' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's' priority 1 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's' priority 2 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's-NOANSWER' priority 1 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's-NOANSWER' priority 1 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-NOANSWER' priority 2 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's-NOANSWER' priority 2 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-BUSY' priority 1 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's-BUSY' priority 1 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-BUSY' priority 2 to macro-stdexten [Dec 1 23:09:34] -- Added extension 's-BUSY' priority 2 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_s-.' priority 1 to macro-stdexten [Dec 1 23:09:34] -- Added extension '_s-.' priority 1 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 'a' priority 1 to macro-stdexten [Dec 1 23:09:34] -- Added extension 'a' priority 1 to macro-stdexten [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'demo' [Dec 1 23:09:34] -- Registered extension context 'demo' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to demo [Dec 1 23:09:34] -- Added extension 's' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to demo [Dec 1 23:09:34] -- Added extension 's' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to demo [Dec 1 23:09:34] -- Added extension 's' priority 3 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to demo [Dec 1 23:09:34] -- Added extension 's' priority 4 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to demo [Dec 1 23:09:34] -- Added extension 's' priority 5 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 6 to demo [Dec 1 23:09:34] -- Added extension 's' priority 6 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '2' priority 1 to demo [Dec 1 23:09:34] -- Added extension '2' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '2' priority 2 to demo [Dec 1 23:09:34] -- Added extension '2' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '3' priority 1 to demo [Dec 1 23:09:34] -- Added extension '3' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '3' priority 2 to demo [Dec 1 23:09:34] -- Added extension '3' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '1000' priority 1 to demo [Dec 1 23:09:34] -- Added extension '1000' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '1234' priority 1 to demo [Dec 1 23:09:34] -- Added extension '1234' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '1234' priority 2 to demo [Dec 1 23:09:34] -- Added extension '1234' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '1235' priority 1 to demo [Dec 1 23:09:34] -- Added extension '1235' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '1236' priority 1 to demo [Dec 1 23:09:34] -- Added extension '1236' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '1236' priority 2 to demo [Dec 1 23:09:34] -- Added extension '1236' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '2222' priority 1 to demo [Dec 1 23:09:34] -- Added extension '2222' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '2222' priority 2 to demo [Dec 1 23:09:34] -- Added extension '2222' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '2222' priority 3 to demo [Dec 1 23:09:34] -- Added extension '2222' priority 3 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '2222' priority 4 to demo [Dec 1 23:09:34] -- Added extension '2222' priority 4 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '3333' priority 1 to demo [Dec 1 23:09:34] -- Added extension '3333' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '3333' priority 2 to demo [Dec 1 23:09:34] -- Added extension '3333' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '3333' priority 3 to demo [Dec 1 23:09:34] -- Added extension '3333' priority 3 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '3333' priority 4 to demo [Dec 1 23:09:34] -- Added extension '3333' priority 4 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '#' priority 1 to demo [Dec 1 23:09:34] -- Added extension '#' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '#' priority 2 to demo [Dec 1 23:09:34] -- Added extension '#' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 't' priority 1 to demo [Dec 1 23:09:34] -- Added extension 't' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 'i' priority 1 to demo [Dec 1 23:09:34] -- Added extension 'i' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '500' priority 1 to demo [Dec 1 23:09:34] -- Added extension '500' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 2 to demo [Dec 1 23:09:34] -- Added extension '500' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 3 to demo [Dec 1 23:09:34] -- Added extension '500' priority 3 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 4 to demo [Dec 1 23:09:34] -- Added extension '500' priority 4 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '600' priority 1 to demo [Dec 1 23:09:34] -- Added extension '600' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 2 to demo [Dec 1 23:09:34] -- Added extension '600' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 3 to demo [Dec 1 23:09:34] -- Added extension '600' priority 3 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 4 to demo [Dec 1 23:09:34] -- Added extension '600' priority 4 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '8500' priority 1 to demo [Dec 1 23:09:34] -- Added extension '8500' priority 1 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '8500' priority 2 to demo [Dec 1 23:09:34] -- Added extension '8500' priority 2 to demo [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'macro-try-meetme' [Dec 1 23:09:34] -- Registered extension context 'macro-try-meetme' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's' priority 3 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's-TRANSFERED' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRANSFERED' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-TRANSFERED' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRANSFERED' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-TRANSFERED' priority 3 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRANSFERED' priority 3 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-TRANSFERED' priority 4 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRANSFERED' priority 4 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-NOTTRANSFERED' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-NOTTRANSFERED' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-NOTTRANSFERED' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-NOTTRANSFERED' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension 's-TRY2IAX' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRY2IAX' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-TRY2IAX' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-TRY2IAX' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-IAX2HOLDER' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-IAX2HOLDER' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-IAX2HOLDER' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-IAX2HOLDER' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-IAX2HOLDER' priority 3 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-IAX2HOLDER' priority 3 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-IAX2HOLDER' priority 4 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-IAX2HOLDER' priority 4 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-CHECKCONFEXIST' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-CHECKCONFEXIST' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-CHECKCONFEXIST' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-CHECKCONFEXIST' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-CONFNOTEMPTY' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-CONFNOTEMPTY' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-HOLDERJOIN' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-HOLDERJOIN' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension 's-INVALID' priority 1 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-INVALID' priority 1 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-INVALID' priority 2 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-INVALID' priority 2 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension 's-INVALID' priority 3 to macro-try-meetme [Dec 1 23:09:34] -- Added extension 's-INVALID' priority 3 to macro-try-meetme [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'intern' [Dec 1 23:09:34] -- Registered extension context 'intern' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '44' priority 1 to intern [Dec 1 23:09:34] -- Added extension '44' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '44' priority 2 to intern [Dec 1 23:09:34] -- Added extension '44' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '45' priority 1 to intern [Dec 1 23:09:34] -- Added extension '45' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '45' priority 2 to intern [Dec 1 23:09:34] -- Added extension '45' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '45' priority 3 to intern [Dec 1 23:09:34] -- Added extension '45' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '45' priority 4 to intern [Dec 1 23:09:34] -- Added extension '45' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '45' priority 5 to intern [Dec 1 23:09:34] -- Added extension '45' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '45' priority 6 to intern [Dec 1 23:09:34] -- Added extension '45' priority 6 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '47' priority 1 to intern [Dec 1 23:09:34] -- Added extension '47' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '46' priority 1 to intern [Dec 1 23:09:34] -- Added extension '46' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '46' priority 2 to intern [Dec 1 23:09:34] -- Added extension '46' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '46' priority 3 to intern [Dec 1 23:09:34] -- Added extension '46' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '46' priority 4 to intern [Dec 1 23:09:34] -- Added extension '46' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '46' priority 5 to intern [Dec 1 23:09:34] -- Added extension '46' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '46' priority 6 to intern [Dec 1 23:09:34] -- Added extension '46' priority 6 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '48' priority 1 to intern [Dec 1 23:09:34] -- Added extension '48' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '20' priority 1 to intern [Dec 1 23:09:34] -- Added extension '20' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '42' priority 1 to intern [Dec 1 23:09:34] -- Added extension '42' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '21' priority 1 to intern [Dec 1 23:09:34] -- Added extension '21' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '24' priority 1 to intern [Dec 1 23:09:34] -- Added extension '24' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_3X' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_3X' priority 2 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_3X' priority 3 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_3X' priority 4 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_3X' priority 5 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_3X' priority 6 to intern [Dec 1 23:09:34] -- Added extension '_3X' priority 6 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_8XX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_8XX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '_8*XX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_8*XX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_90XX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 2 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 3 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 4 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 5 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 6 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 6 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 7 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 7 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90XX' priority 8 to intern [Dec 1 23:09:34] -- Added extension '_90XX' priority 8 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '_90*XX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_90*XX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90*XX' priority 2 to intern [Dec 1 23:09:34] -- Added extension '_90*XX' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90*XX' priority 3 to intern [Dec 1 23:09:34] -- Added extension '_90*XX' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90*XX' priority 4 to intern [Dec 1 23:09:34] -- Added extension '_90*XX' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_90*XX' priority 5 to intern [Dec 1 23:09:34] -- Added extension '_90*XX' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '96' priority 1 to intern [Dec 1 23:09:34] -- Added extension '96' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '96' priority 2 to intern [Dec 1 23:09:34] -- Added extension '96' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '97' priority 1 to intern [Dec 1 23:09:34] -- Added extension '97' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '97' priority 2 to intern [Dec 1 23:09:34] -- Added extension '97' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '97' priority 3 to intern [Dec 1 23:09:34] -- Added extension '97' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '97' priority 4 to intern [Dec 1 23:09:34] -- Added extension '97' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '98' priority 1 to intern [Dec 1 23:09:34] -- Added extension '98' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '98' priority 2 to intern [Dec 1 23:09:34] -- Added extension '98' priority 2 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '98' priority 3 to intern [Dec 1 23:09:34] -- Added extension '98' priority 3 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '98' priority 4 to intern [Dec 1 23:09:34] -- Added extension '98' priority 4 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '98' priority 5 to intern [Dec 1 23:09:34] -- Added extension '98' priority 5 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_99*XXXX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_99*XXXX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_99XXXX' priority 1 to intern [Dec 1 23:09:34] -- Added extension '_99XXXX' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '99*' priority 1 to intern [Dec 1 23:09:34] -- Added extension '99*' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4861 ast_add_extension2: Added extension '99' priority 1 to intern [Dec 1 23:09:34] -- Added extension '99' priority 1 to intern [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'fwd' [Dec 1 23:09:34] -- Registered extension context 'fwd' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '51' priority 1 to fwd [Dec 1 23:09:34] -- Added extension '51' priority 1 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '51' priority 2 to fwd [Dec 1 23:09:34] -- Added extension '51' priority 2 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '52' priority 1 to fwd [Dec 1 23:09:34] -- Added extension '52' priority 1 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '52' priority 2 to fwd [Dec 1 23:09:34] -- Added extension '52' priority 2 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_5' priority 1 to fwd [Dec 1 23:09:34] -- Added extension '_5' priority 1 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '_5' priority 2 to fwd [Dec 1 23:09:34] -- Added extension '_5' priority 2 to fwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'fromiaxfwd' [Dec 1 23:09:34] -- Registered extension context 'fromiaxfwd' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '643203' priority 1 to fromiaxfwd [Dec 1 23:09:34] -- Added extension '643203' priority 1 to fromiaxfwd [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'pstn' [Dec 1 23:09:34] -- Registered extension context 'pstn' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '_0X.' priority 1 to pstn [Dec 1 23:09:34] -- Added extension '_0X.' priority 1 to pstn [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'capi-in' [Dec 1 23:09:34] -- Registered extension context 'capi-in' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4876 ast_add_extension2: Added extension '669678' priority 1 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 1 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 2 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 2 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 3 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 3 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 4 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 4 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 5 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 5 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 6 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 6 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:4842 ast_add_extension2: Added extension '669678' priority 7 to capi-in [Dec 1 23:09:34] -- Added extension '669678' priority 7 to capi-in [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'rns' [Dec 1 23:09:34] -- Registered extension context 'rns' [Dec 1 23:09:34] DEBUG[13898]: pbx.c:3674 ast_context_create: Registered context 'default' [Dec 1 23:09:34] -- Registered extension context 'default' [Dec 1 23:09:34] -- Including context 'intern' in context 'default' [Dec 1 23:09:34] -- Including context 'pstn' in context 'default' [Dec 1 23:09:34] -- Including context 'fwd' in context 'default' [Dec 1 23:09:34] [pbx_spool.so][Dec 1 23:09:34] => (Outgoing Spool Support) [Dec 1 23:09:34] [pbx_loopback.so][Dec 1 23:09:34] => (Loopback Switch) [Dec 1 23:09:34] [pbx_realtime.so][Dec 1 23:09:34] => (Realtime Switch) [Dec 1 23:09:34] [pbx_ael.so][Dec 1 23:09:34] => (Asterisk Extension Language Compiler) [Dec 1 23:09:34] WARNING[13898]: pbx_ael.c:1172 ast_ael_compile: Unable to open '/etc/asterisk/extensions.ael': No such file or directory [Dec 1 23:09:34] WARNING[13898]: pbx.c:3761 ast_merge_contexts_and_delete: Requested contexts didn't get merged [Dec 1 23:09:34] [chan_sip.so][Dec 1 23:09:34] => (Session Initiation Protocol (SIP)) [Dec 1 23:09:34] == Parsing '/etc/asterisk/sip.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/sip.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] WARNING[13898]: config.c:525 process_text_line: No '=' (equal sign) in line 115 of sip.conf [Dec 1 23:09:34] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:34] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:34] DEBUG[13898]: db.c:200 ast_db_get: Unable to find key 'giga01' in family 'SIP/Registry' [Dec 1 23:09:34] -- parse_srv: SRV mapped to host sipgate.de, port 5060 [Dec 1 23:09:34] == SIP Listening on 0.0.0.0:5060 [Dec 1 23:09:34] == Using TOS bits 0 [Dec 1 23:09:34] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) [Dec 1 23:09:34] == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) [Dec 1 23:09:34] == Registered application 'SIPDtmfMode' [Dec 1 23:09:34] == Registered application 'SIPAddHeader' [Dec 1 23:09:34] == Registered application 'SIPGetHeader' [Dec 1 23:09:34] == Registered custom function SIP_HEADER [Dec 1 23:09:34] == Registered custom function SIPPEER [Dec 1 23:09:34] == Registered custom function SIPCHANINFO [Dec 1 23:09:34] == Registered custom function CHECKSIPDOMAIN [Dec 1 23:09:34] == Manager registered action SIPpeers [Dec 1 23:09:34] == Manager registered action SIPshowpeer [Dec 1 23:09:34] [chan_agent.so][Dec 1 23:09:34] => (Agent Proxy Channel) [Dec 1 23:09:34] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Agent' (Call Agent Proxy Channel) [Dec 1 23:09:34] == Registered channel type 'Agent' (Call Agent Proxy Channel) [Dec 1 23:09:34] == Registered application 'AgentLogin' [Dec 1 23:09:34] == Registered application 'AgentCallbackLogin' [Dec 1 23:09:34] == Registered application 'AgentMonitorOutgoing' [Dec 1 23:09:34] == Manager registered action Agents [Dec 1 23:09:34] == Manager registered action AgentLogoff [Dec 1 23:09:34] == Manager registered action AgentCallbackLogin [Dec 1 23:09:34] == Parsing '/etc/asterisk/agents.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/agents.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] [chan_mgcp.so][Dec 1 23:09:34] => (Media Gateway Control Protocol (MGCP)) [Dec 1 23:09:34] == Parsing '/etc/asterisk/mgcp.conf': [Dec 1 23:09:34] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/mgcp.conf [Dec 1 23:09:34] Found [Dec 1 23:09:34] == MGCP Listening on 0.0.0.0:2727 [Dec 1 23:09:34] == Using TOS bits 0 [Dec 1 23:09:34] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) [Dec 1 23:09:34] == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) [Dec 1 23:09:34] [chan_iax2.so][Dec 1 23:09:34] => (Inter Asterisk eXchange (Ver 2)) [Dec 1 23:09:34] == Registered custom function IAXPEER [Dec 1 23:09:35] == Registered application 'IAX2Provision' [Dec 1 23:09:35] == Manager registered action IAXpeers [Dec 1 23:09:35] == Manager registered action IAXnetstats [Dec 1 23:09:35] == Parsing '/etc/asterisk/iax.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iax.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] == Using TOS bits 16 [Dec 1 23:09:35] == Binding IAX2 to default address 0.0.0.0:4569 [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 62.50.96.78/255.255.255.255 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: db.c:200 ast_db_get: Unable to find key 'laden' in family 'IAX/Registry' [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] WARNING[13898]: chan_iax2.c:8395 build_peer: Set peer->pokefreqnotok to 10000 [Dec 1 23:09:35] -- Seeding 'wolfgang' at 192.168.2.3:4569 for 60 [Dec 1 23:09:35] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 4 (Invalid) [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] -- Seeding 'iaxcomm2' at 192.168.2.8:4569 for 60 [Dec 1 23:09:35] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 4 (Invalid) [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.2.0/24/24 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: db.c:200 ast_db_get: Unable to find key 'iaxcomm' in family 'IAX/Registry' [Dec 1 23:09:35] -- doing lookup for '216.207.245.47' [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.40.101/255.255.255.255 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: acl.c:199 ast_append_ha: 192.168.40.101/255.255.255.255 appended to acl for peer [Dec 1 23:09:35] DEBUG[13898]: db.c:200 ast_db_get: Unable to find key 'horst' in family 'IAX/Registry' [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Dec 1 23:09:35] == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Dec 1 23:09:35] == IAX Ready and Listening [Dec 1 23:09:35] DEBUG[13898]: chan_iax2.c:7751 iax2_do_register: Allocate call number [Dec 1 23:09:35] DEBUG[13898]: chan_iax2.c:7757 iax2_do_register: Registration created on call 1 [Dec 1 23:09:35] == Loaded firmware 'iaxy.bin' [Dec 1 23:09:35] == Parsing '/etc/asterisk/iaxprov.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iaxprov.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] -- Loaded provisioning template 'default' [Dec 1 23:09:35] [chan_local.so][Dec 1 23:09:35] => (Local Proxy Channel) [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Local' (Local Proxy Channel Driver) [Dec 1 23:09:35] == Registered channel type 'Local' (Local Proxy Channel Driver) [Dec 1 23:09:35] [chan_skinny.so][Dec 1 23:09:35] => (Skinny Client Control Protocol (Skinny)) [Dec 1 23:09:35] == Parsing '/etc/asterisk/skinny.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/skinny.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] == Skinny listening on 0.0.0.0:2000 [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) [Dec 1 23:09:35] == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) [Dec 1 23:09:35] [chan_features.so][Dec 1 23:09:35] => (Feature Proxy Channel) [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Feature' (Feature Proxy Channel Driver) [Dec 1 23:09:35] == Registered channel type 'Feature' (Feature Proxy Channel Driver) [Dec 1 23:09:35] [chan_oss.so][Dec 1 23:09:35] => (OSS Console Channel Driver) [Dec 1 23:09:35] == Parsing '/etc/asterisk/oss.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/oss.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Console' (OSS Console Channel Driver) [Dec 1 23:09:35] == Registered channel type 'Console' (OSS Console Channel Driver) [Dec 1 23:09:35] [chan_phone.so][Dec 1 23:09:35] => (Linux Telephony API Support) [Dec 1 23:09:35] == Parsing '/etc/asterisk/phone.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/phone.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Phone' (Standard Linux Telephony API Driver) [Dec 1 23:09:35] == Registered channel type 'Phone' (Standard Linux Telephony API Driver) [Dec 1 23:09:35] [chan_zap.so][Dec 1 23:09:35] DEBUG[13916]: chan_iax2.c:7257 socket_read: Peer lastms 48, historicms 24, maxms 2000 [Dec 1 23:09:35] => (Zapata Telephony) [Dec 1 23:09:35] == Parsing '/etc/asterisk/zapata.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/zapata.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] -- Automatically generated pseudo channel [Dec 1 23:09:35] DEBUG[13898]: channel.c:344 ast_channel_register: Registered handler for 'Zap' (Zapata Telephony Driver) [Dec 1 23:09:35] == Registered channel type 'Zap' (Zapata Telephony Driver) [Dec 1 23:09:35] == Manager registered action ZapTransfer [Dec 1 23:09:35] == Manager registered action ZapHangup [Dec 1 23:09:35] == Manager registered action ZapDialOffhook [Dec 1 23:09:35] == Manager registered action ZapDNDon [Dec 1 23:09:35] == Manager registered action ZapDNDoff [Dec 1 23:09:35] == Manager registered action ZapShowChannels [Dec 1 23:09:35] [app_cdr.so][Dec 1 23:09:35] => (Tell Asterisk to not maintain a CDR for the current call) [Dec 1 23:09:35] == Registered application 'NoCDR' [Dec 1 23:09:35] [app_txtcidname.so][Dec 1 23:09:35] => (TXTCIDName) [Dec 1 23:09:35] == Registered application 'TXTCIDName' [Dec 1 23:09:35] [app_md5.so][Dec 1 23:09:35] => (MD5 checksum applications) [Dec 1 23:09:35] == Registered application 'MD5Check' [Dec 1 23:09:35] == Registered application 'MD5' [Dec 1 23:09:35] [app_settransfercapability.so][Dec 1 23:09:35] => (Set ISDN Transfer Capability) [Dec 1 23:09:35] == Registered application 'SetTransferCapability' [Dec 1 23:09:35] [app_page.so][Dec 1 23:09:35] => (Page Multiple Phones) [Dec 1 23:09:35] == Registered application 'Page' [Dec 1 23:09:35] [codec_ulaw.so][Dec 1 23:09:35] => (Mu-law Coder/Decoder) [Dec 1 23:09:35] == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] [app_system.so][Dec 1 23:09:35] => (Generic System() application) [Dec 1 23:09:35] == Registered application 'TrySystem' [Dec 1 23:09:35] == Registered application 'System' [Dec 1 23:09:35] [app_record.so][Dec 1 23:09:35] => (Trivial Record Application) [Dec 1 23:09:35] == Registered application 'Record' [Dec 1 23:09:35] [app_zapateller.so][Dec 1 23:09:35] => (Block Telemarketers with Special Information Tone) [Dec 1 23:09:35] == Registered application 'Zapateller' [Dec 1 23:09:35] [app_festival.so][Dec 1 23:09:35] => (Simple Festival Interface) [Dec 1 23:09:35] == Registered application 'Festival' [Dec 1 23:09:35] [app_authenticate.so][Dec 1 23:09:35] => (Authentication Application) [Dec 1 23:09:35] == Registered application 'Authenticate' [Dec 1 23:09:35] [app_while.so][Dec 1 23:09:35] => (While Loops and Conditional Execution) [Dec 1 23:09:35] == Registered application 'While' [Dec 1 23:09:35] == Registered application 'ExecIf' [Dec 1 23:09:35] == Registered application 'EndWhile' [Dec 1 23:09:35] [app_setrdnis.so][Dec 1 23:09:35] => (Set RDNIS Number) [Dec 1 23:09:35] == Registered application 'SetRDNIS' [Dec 1 23:09:35] [app_zapscan.so][Dec 1 23:09:35] => (Scan Zap channels application) [Dec 1 23:09:35] == Registered application 'ZapScan' [Dec 1 23:09:35] [codec_lpc10.so][Dec 1 23:09:35] => (LPC10 2.4kbps (signed linear) Voice Coder) [Dec 1 23:09:35] == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 5 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] == Registered translator 'lintolpc10' from format slin to lpc10, cost 37 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] [app_url.so][Dec 1 23:09:35] => (Send URL Applications) [Dec 1 23:09:35] == Registered application 'SendURL' [Dec 1 23:09:35] [app_disa.so][Dec 1 23:09:35] => (DISA (Direct Inward System Access) Application) [Dec 1 23:09:35] == Registered application 'DISA' [Dec 1 23:09:35] [app_adsiprog.so][Dec 1 23:09:35] => (Asterisk ADSI Programming Application) [Dec 1 23:09:35] == Registered application 'ADSIProg' [Dec 1 23:09:35] [app_setcallerid.so][Dec 1 23:09:35] => (Set CallerID Application) [Dec 1 23:09:35] == Registered application 'SetCallerPres' [Dec 1 23:09:35] == Registered application 'SetCallerID' [Dec 1 23:09:35] [app_queue.so][Dec 1 23:09:35] => (True Call Queueing) [Dec 1 23:09:35] == Registered application 'Queue' [Dec 1 23:09:35] == Manager registered action Queues [Dec 1 23:09:35] == Manager registered action QueueStatus [Dec 1 23:09:35] == Manager registered action QueueAdd [Dec 1 23:09:35] == Manager registered action QueueRemove [Dec 1 23:09:35] == Manager registered action QueuePause [Dec 1 23:09:35] == Registered application 'AddQueueMember' [Dec 1 23:09:35] == Registered application 'RemoveQueueMember' [Dec 1 23:09:35] == Registered application 'PauseQueueMember' [Dec 1 23:09:35] == Registered application 'UnpauseQueueMember' [Dec 1 23:09:35] == Registered custom function QUEUEAGENTCOUNT [Dec 1 23:09:35] == Parsing '/etc/asterisk/queues.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/queues.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] [app_softhangup.so][Dec 1 23:09:35] => (Hangs up the requested channel) [Dec 1 23:09:35] == Registered application 'SoftHangup' [Dec 1 23:09:35] [app_talkdetect.so][Dec 1 23:09:35] => (Playback with Talk Detection) [Dec 1 23:09:35] == Registered application 'BackgroundDetect' [Dec 1 23:09:35] [app_dumpchan.so][Dec 1 23:09:35] => (Dump Info About The Calling Channel) [Dec 1 23:09:35] == Registered application 'DumpChan' [Dec 1 23:09:35] [app_dictate.so][Dec 1 23:09:35] => (Virtual Dictation Machine) [Dec 1 23:09:35] == Registered application 'Dictate' [Dec 1 23:09:35] [codec_gsm.so][Dec 1 23:09:35] => (GSM/PCM16 (signed linear) Codec Translator) [Dec 1 23:09:35] == Registered translator 'gsmtolin' from format gsm to slin, cost 2 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] == Registered translator 'lintogsm' from format slin to gsm, cost 35 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] [func_enum.so][Dec 1 23:09:35] => (ENUM Related Functions) [Dec 1 23:09:35] == Registered custom function ENUMLOOKUP [Dec 1 23:09:35] == Registered custom function TXTCIDNAME [Dec 1 23:09:35] [app_senddtmf.so][Dec 1 23:09:35] => (Send DTMF digits Application) [Dec 1 23:09:35] == Registered application 'SendDTMF' [Dec 1 23:09:35] [app_parkandannounce.so][Dec 1 23:09:35] => (Call Parking and Announce Application) [Dec 1 23:09:35] == Registered application 'ParkAndAnnounce' [Dec 1 23:09:35] [app_setcidname.so][Dec 1 23:09:35] => (Set CallerID Name) [Dec 1 23:09:35] == Registered application 'SetCIDName' [Dec 1 23:09:35] [app_lookupblacklist.so][Dec 1 23:09:35] => (Look up Caller*ID name/number from blacklist database) [Dec 1 23:09:35] == Registered application 'LookupBlacklist' [Dec 1 23:09:35] [app_waitforring.so][Dec 1 23:09:35] => (Waits until first ring after time) [Dec 1 23:09:35] == Registered application 'WaitForRing' [Dec 1 23:09:35] [app_externalivr.so][Dec 1 23:09:35] => (External IVR Interface Application) [Dec 1 23:09:35] == Registered application 'ExternalIVR' [Dec 1 23:09:35] [app_stack.so][Dec 1 23:09:35] => (Stack Routines) [Dec 1 23:09:35] == Registered application 'StackPop' [Dec 1 23:09:35] == Registered application 'Return' [Dec 1 23:09:35] == Registered application 'GosubIf' [Dec 1 23:09:35] == Registered application 'Gosub' [Dec 1 23:09:35] [app_sms.so][Dec 1 23:09:35] => (SMS/PSTN handler) [Dec 1 23:09:35] == Registered application 'SMS' [Dec 1 23:09:35] [func_uri.so][Dec 1 23:09:35] => (URI encode/decode functions) [Dec 1 23:09:35] == Registered custom function URIDECODE [Dec 1 23:09:35] == Registered custom function URIENCODE [Dec 1 23:09:35] [app_directed_pickup.so][Dec 1 23:09:35] => (Directed Call Pickup Application) [Dec 1 23:09:35] == Registered application 'Pickup' [Dec 1 23:09:35] [app_db.so][Dec 1 23:09:35] => (Database Access Functions) [Dec 1 23:09:35] == Registered application 'DBget' [Dec 1 23:09:35] == Registered application 'DBput' [Dec 1 23:09:35] == Registered application 'DBdel' [Dec 1 23:09:35] == Registered application 'DBdeltree' [Dec 1 23:09:35] [app_chanisavail.so][Dec 1 23:09:35] => (Check channel availability) [Dec 1 23:09:35] == Registered application 'ChanIsAvail' [Dec 1 23:09:35] [app_enumlookup.so][Dec 1 23:09:35] => (ENUM Lookup) [Dec 1 23:09:35] == Registered application 'EnumLookup' [Dec 1 23:09:35] == Parsing '/etc/asterisk/enum.conf': [Dec 1 23:09:35] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/enum.conf [Dec 1 23:09:35] Found [Dec 1 23:09:35] [app_transfer.so][Dec 1 23:09:35] => (Transfer) [Dec 1 23:09:35] == Registered application 'Transfer' [Dec 1 23:09:35] [app_setcidnum.so][Dec 1 23:09:35] => (Set CallerID Number) [Dec 1 23:09:35] == Registered application 'SetCIDNum' [Dec 1 23:09:35] [app_hasnewvoicemail.so][Dec 1 23:09:35] => (Indicator for whether a voice mailbox has messages in a given folder.[) [Dec 1 23:09:35] == Registered custom function VMCOUNT [Dec 1 23:09:35] == Registered application 'HasVoicemail' [Dec 1 23:09:35] == Registered application 'HasNewVoicemail' [Dec 1 23:09:35] [app_sayunixtime.so][Dec 1 23:09:35] => (Say time) [Dec 1 23:09:35] == Registered application 'SayUnixTime' [Dec 1 23:09:35] == Registered application 'DateTime' [Dec 1 23:09:35] [app_cut.so][Dec 1 23:09:35] => (Cut out information from a string) [Dec 1 23:09:35] == Registered custom function CUT [Dec 1 23:09:35] == Registered custom function SORT [Dec 1 23:09:35] == Registered application 'Sort' [Dec 1 23:09:35] == Registered application 'Cut' [Dec 1 23:09:35] [app_read.so][Dec 1 23:09:35] => (Read Variable Application) [Dec 1 23:09:35] == Registered application 'Read' [Dec 1 23:09:35] [app_setcdruserfield.so][Dec 1 23:09:35] => (CDR user field apps) [Dec 1 23:09:35] == Registered application 'SetCDRUserField' [Dec 1 23:09:35] == Registered application 'AppendCDRUserField' [Dec 1 23:09:35] == Manager registered action SetCDRUserField [Dec 1 23:09:35] [app_random.so][Dec 1 23:09:35] => (Random goto) [Dec 1 23:09:35] == Registered application 'Random' [Dec 1 23:09:35] [app_ices.so][Dec 1 23:09:35] => (Encode and Stream via icecast and ices) [Dec 1 23:09:35] == Registered application 'ICES' [Dec 1 23:09:35] [app_eval.so][Dec 1 23:09:35] => (Reevaluates strings) [Dec 1 23:09:35] == Registered application 'Eval' [Dec 1 23:09:35] [app_nbscat.so][Dec 1 23:09:35] => (Silly NBS Stream Application) [Dec 1 23:09:35] == Registered application 'NBScat' [Dec 1 23:09:35] [app_sendtext.so][Dec 1 23:09:35] => (Send Text Applications) [Dec 1 23:09:35] == Registered application 'SendText' [Dec 1 23:09:35] [app_exec.so][Dec 1 23:09:35] => (Executes applications) [Dec 1 23:09:35] == Registered application 'Exec' [Dec 1 23:09:35] [app_groupcount.so][Dec 1 23:09:35] => (Group Management Routines) [Dec 1 23:09:35] == Registered application 'GetGroupCount' [Dec 1 23:09:35] == Registered application 'SetGroup' [Dec 1 23:09:35] == Registered application 'CheckGroup' [Dec 1 23:09:35] == Registered application 'GetGroupMatchCount' [Dec 1 23:09:35] [app_math.so][Dec 1 23:09:35] => (Basic Math Functions) [Dec 1 23:09:35] == Registered application 'Math' [Dec 1 23:09:35] [app_waitforsilence.so][Dec 1 23:09:35] => (Wait For Silence) [Dec 1 23:09:35] == Registered application 'WaitForSilence' [Dec 1 23:09:35] [app_mixmonitor.so][Dec 1 23:09:35] => (Mixed Audio Monitoring Application) [Dec 1 23:09:35] == Registered application 'MixMonitor' [Dec 1 23:09:35] [app_zapras.so][Dec 1 23:09:35] => (Zap RAS Application) [Dec 1 23:09:35] == Registered application 'ZapRAS' [Dec 1 23:09:35] [codec_ilbc.so][Dec 1 23:09:35] => (iLBC/PCM16 (signed linear) Codec Translator) [Dec 1 23:09:35] == Registered translator 'ilbctolin' from format ilbc to slin, cost 37 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:35] == Registered translator 'lintoilbc' from format slin to ilbc, cost 95 [Dec 1 23:09:35] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:35] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] [app_alarmreceiver.so][Dec 1 23:09:36] => (Alarm Receiver for Asterisk) [Dec 1 23:09:36] == Parsing '/etc/asterisk/alarmreceiver.conf': [Dec 1 23:09:36] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/alarmreceiver.conf [Dec 1 23:09:36] Found [Dec 1 23:09:36] == Registered application 'AlarmReceiver' [Dec 1 23:09:36] [app_userevent.so][Dec 1 23:09:36] => (Custom User Event Application) [Dec 1 23:09:36] == Registered application 'UserEvent' [Dec 1 23:09:36] [app_verbose.so][Dec 1 23:09:36] => (Send verbose output) [Dec 1 23:09:36] == Registered application 'Verbose' [Dec 1 23:09:36] [app_test.so][Dec 1 23:09:36] => (Interface Test Application) [Dec 1 23:09:36] == Registered application 'TestClient' [Dec 1 23:09:36] == Registered application 'TestServer' [Dec 1 23:09:36] [app_forkcdr.so][Dec 1 23:09:36] => (Fork The CDR into 2 separate entities.) [Dec 1 23:09:36] == Registered application 'ForkCDR' [Dec 1 23:09:36] [app_realtime.so][Dec 1 23:09:36] => (Realtime Data Lookup/Rewrite) [Dec 1 23:09:36] == Registered application 'RealTimeUpdate' [Dec 1 23:09:36] == Registered application 'RealTime' [Dec 1 23:09:36] [app_chanspy.so][Dec 1 23:09:36] => (Listen to the audio of an active channel ) [Dec 1 23:09:36] == Registered application 'ChanSpy' [Dec 1 23:09:36] [app_meetme.so][Dec 1 23:09:36] => (MeetMe conference bridge) [Dec 1 23:09:36] == Parsing '/etc/asterisk/meetme.conf': [Dec 1 23:09:36] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/meetme.conf [Dec 1 23:09:36] Found [Dec 1 23:09:36] == Registered application 'MeetMeAdmin' [Dec 1 23:09:36] == Registered application 'MeetMeCount' [Dec 1 23:09:36] == Registered application 'MeetMe' [Dec 1 23:09:36] [format_g723.so][Dec 1 23:09:36] => (G.723.1 Simple Timestamp File Format) [Dec 1 23:09:36] == Registered file format g723sf, extension(s) g723|g723sf [Dec 1 23:09:36] [func_callerid.so][Dec 1 23:09:36] => (Caller ID related dialplan function) [Dec 1 23:09:36] == Registered custom function CALLERID [Dec 1 23:09:36] [codec_adpcm.so][Dec 1 23:09:36] => (Adaptive Differential PCM Coder/Decoder) [Dec 1 23:09:36] == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] [codec_alaw.so][Dec 1 23:09:36] => (A-law Coder/Decoder) [Dec 1 23:09:36] == Registered translator 'alawtolin' from format alaw to slin, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] == Registered translator 'lintoalaw' from format slin to alaw, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] [codec_a_mu.so][Dec 1 23:09:36] => (A-law and Mulaw direct Coder/Decoder) [Dec 1 23:09:36] == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 37 cost path from unknown to g723, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 37 cost path from unknown to g723, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] [codec_g726.so][Dec 1 23:09:36] => (ITU G.726-32kbps G726 Transcoder) [Dec 1 23:09:36] == Registered translator 'g726tolin' from format g726 to slin, cost 34 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 37 cost path from unknown to g723, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 69 cost path from ulaw to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 71 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 129 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] == Registered translator 'lintog726' from format slin to g726, cost 4 [Dec 1 23:09:36] DEBUG[13898]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from g723 to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from g723 to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from gsm to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 5 cost path from gsm to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from gsm to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 37 cost path from unknown to g723, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to ulaw, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 39 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 97 cost path from unknown to unknown, via 2 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 69 cost path from ulaw to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 35 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 71 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 129 cost path from ulaw to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 36 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 96 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 40 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 100 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 72 cost path from unknown to g723, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to gsm, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 41 cost path from unknown to ulaw, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 38 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] DEBUG[13898]: translate.c:322 rebuild_matrix: Discovered 74 cost path from unknown to unknown, via 6 [Dec 1 23:09:36] [format_gsm.so][Dec 1 23:09:36] => (Raw GSM data) [Dec 1 23:09:36] == Registered file format gsm, extension(s) gsm [Dec 1 23:09:36] [format_wav.so][Dec 1 23:09:36] => (Microsoft WAV format (8000hz Signed Linear)) [Dec 1 23:09:36] == Registered file format wav, extension(s) wav [Dec 1 23:09:36] [format_wav_gsm.so][Dec 1 23:09:36] => (Microsoft WAV format (Proprietary GSM)) [Dec 1 23:09:36] == Registered file format wav49, extension(s) WAV|wav49 [Dec 1 23:09:36] [format_vox.so][Dec 1 23:09:36] => (Dialogic VOX (ADPCM) File Format) [Dec 1 23:09:36] == Registered file format vox, extension(s) vox [Dec 1 23:09:36] [format_pcm.so][Dec 1 23:09:36] => (Raw uLaw 8khz Audio support (PCM)) [Dec 1 23:09:36] == Registered file format pcm, extension(s) pcm|ulaw|ul|mu [Dec 1 23:09:36] [format_g729.so][Dec 1 23:09:36] => (Raw G729 data) [Dec 1 23:09:36] == Registered file format g729, extension(s) g729 [Dec 1 23:09:36] [format_pcm_alaw.so][Dec 1 23:09:36] => (Raw aLaw 8khz PCM Audio support) [Dec 1 23:09:36] == Registered file format alaw, extension(s) alaw|al [Dec 1 23:09:36] [format_h263.so][Dec 1 23:09:36] => (Raw h263 data) [Dec 1 23:09:36] == Registered file format h263, extension(s) h263 [Dec 1 23:09:36] [format_g726.so][Dec 1 23:09:36] => (Raw G.726 (16/24/32/40kbps) data) [Dec 1 23:09:36] == Registered file format g726-40, extension(s) g726-40 [Dec 1 23:09:36] == Registered file format g726-32, extension(s) g726-32 [Dec 1 23:09:36] == Registered file format g726-24, extension(s) g726-24 [Dec 1 23:09:36] == Registered file format g726-16, extension(s) g726-16 [Dec 1 23:09:36] [format_ilbc.so][Dec 1 23:09:36] => (Raw iLBC data) [Dec 1 23:09:36] == Registered file format iLBC, extension(s) ilbc [Dec 1 23:09:36] [format_sln.so][Dec 1 23:09:36] => (Raw Signed Linear Audio support (SLN)) [Dec 1 23:09:36] == Registered file format sln, extension(s) sln|raw [Dec 1 23:09:36] [format_jpeg.so][Dec 1 23:09:36] => (JPEG (Joint Picture Experts Group) Image Format) [Dec 1 23:09:36] == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) [Dec 1 23:09:36] [cdr_manager.so][Dec 1 23:09:36] => (Asterisk Call Manager CDR Backend) [Dec 1 23:09:36] == Parsing '/etc/asterisk/cdr_manager.conf': [Dec 1 23:09:36] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/cdr_manager.conf [Dec 1 23:09:36] Found [Dec 1 23:09:36] [cdr_custom.so][Dec 1 23:09:36] => (Customizable Comma Separated Values CDR Backend) [Dec 1 23:09:36] WARNING[13898]: cdr_custom.c:98 load_config: Failed to load configuration file. Module not activated. [Dec 1 23:09:36] [app_image.so][Dec 1 23:09:36] => (Image Transmission Application) [Dec 1 23:09:36] == Registered application 'SendImage' [Dec 1 23:09:36] [app_mp3.so][Dec 1 23:09:36] => (Silly MP3 Application) [Dec 1 23:09:36] == Registered application 'MP3Player' [Dec 1 23:09:36] [app_milliwatt.so][Dec 1 23:09:36] => (Digital Milliwatt (mu-law) Test Application) [Dec 1 23:09:36] == Registered application 'Milliwatt' [Dec 1 23:09:36] [app_privacy.so][Dec 1 23:09:36] => (Require phone number to be entered, if no CallerID sent) [Dec 1 23:09:36] == Registered application 'PrivacyManager' [Dec 1 23:09:36] [app_flash.so][Dec 1 23:09:36] => (Flash zap trunk application) [Dec 1 23:09:36] == Registered application 'Flash' [Dec 1 23:09:36] [app_capiNoES.so][Dec 1 23:09:36] => ((CAPI*) No Echo Suppression.) [Dec 1 23:09:36] == Registered application 'capiNoES' [Dec 1 23:09:36] [app_dial.so][Dec 1 23:09:36] => (Dialing Application) [Dec 1 23:09:36] == Registered application 'Dial' [Dec 1 23:09:36] == Registered application 'RetryDial' [Dec 1 23:09:36] [app_playback.so][Dec 1 23:09:36] => (Sound File Playback Application) [Dec 1 23:09:36] == Registered application 'Playback' [Dec 1 23:09:36] [app_voicemail.so][Dec 1 23:09:36] => (Comedian Mail (Voicemail System)) [Dec 1 23:09:36] == Registered application 'VoiceMail' [Dec 1 23:09:36] == Registered application 'VoiceMailMain' [Dec 1 23:09:36] == Registered application 'MailboxExists' [Dec 1 23:09:36] == Registered application 'VMAuthenticate' [Dec 1 23:09:36] == Parsing '/etc/asterisk/voicemail.conf': [Dec 1 23:09:36] DEBUG[13898]: config.c:595 config_text_file_load: Parsing /etc/asterisk/voicemail.conf [Dec 1 23:09:36] Found [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6006 load_config: VM Review Option disabled globally [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6012 load_config: VM Operator break disabled globally [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6018 load_config: VM CID Info before msg disabled globally [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6030 load_config: ENVELOPE before msg enabled globally [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6036 load_config: Duration info before msg enabled globally [Dec 1 23:09:36] DEBUG[13898]: app_voicemail.c:6051 load_config: We are not going to skip to the next msg after save/delete [Dec 1 23:09:36] [app_directory.so][Dec 1 23:09:36] => (Extension Directory) [Dec 1 23:09:36] == Registered application 'Directory' [Dec 1 23:09:36] [app_echo.so][Dec 1 23:09:36] => (Simple Echo Application) [Dec 1 23:09:36] == Registered application 'Echo' [Dec 1 23:09:36] [app_getcpeid.so][Dec 1 23:09:36] => (Get ADSI CPE ID) [Dec 1 23:09:36] == Registered application 'GetCPEID' [Dec 1 23:09:36] [app_lookupcidname.so][Dec 1 23:09:36] => (Look up CallerID Name from local database) [Dec 1 23:09:36] == Registered application 'LookupCIDName' [Dec 1 23:09:36] [app_macro.so][Dec 1 23:09:36] => (Extension Macros) [Dec 1 23:09:36] == Registered application 'MacroExit' [Dec 1 23:09:36] == Registered application 'MacroIf' [Dec 1 23:09:36] == Registered application 'Macro' [Dec 1 23:09:36] [app_controlplayback.so][Dec 1 23:09:36] => (Control Playback Application) [Dec 1 23:09:36] == Registered application 'ControlPlayback' [Dec 1 23:09:36] [app_readfile.so][Dec 1 23:09:36] => (Stores output of file into a variable) [Dec 1 23:09:36] == Registered application 'ReadFile' [Dec 1 23:09:36] [app_zapbarge.so][Dec 1 23:09:36] => (Barge in on Zap channel application) [Dec 1 23:09:36] == Registered application 'ZapBarge' [Dec 1 23:09:36] [format_au.so][Dec 1 23:09:36] => (Sun Microsystems AU format (signed linear)) [Dec 1 23:09:36] == Registered file format au, extension(s) au [Dec 1 23:09:36] [cdr_csv.so][Dec 1 23:09:36] => (Comma Separated Values CDR Backend) [Dec 1 23:09:36] [app_asyncgoto.so][Dec 1 23:09:36] => (Async goto Application) [Dec 1 23:09:36] == Registered application 'AsyncGoto' [Dec 1 23:09:36] Asterisk Ready. ]1;Asterisk]2;Asterisk Console on 'musicman' (pid 13898)*CLI> [Dec 1 23:09:36] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.8 with 192.168.2.0 [Dec 1 23:09:36] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:09:36] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:09:36] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 1 (Not in use) [Dec 1 23:09:36] DEBUG[13922]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:09:36] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.8 with 192.168.2.0 [Dec 1 23:09:36] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:09:36] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:09:36] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 1 (Not in use) [Dec 1 23:09:36] DEBUG[13923]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:09:40] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=5 *CLI> [Dec 1 23:09:42] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=5 [Dec 1 23:09:42] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=5 [Dec 1 23:09:42] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=5 [Dec 1 23:09:42] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=5 [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Challenge' s[Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Login' [Dec 1 23:09:43] == Parsing '/etc/asterisk/manager.conf': [Dec 1 23:09:43] DEBUG[13924]: config.c:595 config_text_file_load: Parsing /etc/asterisk/manager.conf [Dec 1 23:09:43] Found [Dec 1 23:09:43] WARNING[13924]: acl.c:177 ast_append_ha: 24,127.0.0.1 is not a valid netmask [Dec 1 23:09:43] == Manager 'wolfgang' logged on from 192.168.2.21 [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'QueueStatus' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Status' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'ZapShowChannels' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:43] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' et deb[Dec 1 23:09:45] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.3 with 192.168.2.0 [Dec 1 23:09:45] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:09:45] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:09:45] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 1 (Not in use) [Dec 1 23:09:45] DEBUG[13925]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:09:45] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:45] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:45] DEBUG[13924]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:09:45] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.3 with 192.168.2.0 [Dec 1 23:09:45] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:09:45] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:09:45] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 1 (Not in use) [Dec 1 23:09:45] DEBUG[13926]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. ug 4 Core debug was 5 and is now 4 *CLI> set verbose 4 Verbosity was 5 and is now 4 *CLI> [Dec 1 23:10:00] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:00] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:00] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:00] DEBUG[13927]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:00] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 5, having received INVAL [Dec 1 23:10:00] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 5 [Dec 1 23:10:02] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:02] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:02] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:02] DEBUG[13933]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:02] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 6, having received INVAL [Dec 1 23:10:02] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 6 [Dec 1 23:10:10] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:12] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:12] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:12] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:12] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:12] DEBUG[13934]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:12] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 7, having received INVAL [Dec 1 23:10:12] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 7 [Dec 1 23:10:14] == ISDN1: Incoming call '08157997950' -> '669678' [Dec 1 23:10:14] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for CAPI/ISDN1/669678 - state 2 (In use) [Dec 1 23:10:14] DEBUG[13936]: app_queue.c:500 changethread: Device 'CAPI/ISDN1/669678' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:14] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'DBget' [Dec 1 23:10:14] -- Executing DBget("CAPI/ISDN1/669678-0", "ansage=ansage/08157997950") in new stack [Dec 1 23:10:14] WARNING[13937]: app_db.c:226 get_exec: This application has been deprecated, please use the ${DB(family/key)} function instead. [Dec 1 23:10:14] -- DBget: varname=ansage, family=ansage, key=08157997950 [Dec 1 23:10:14] DEBUG[13937]: db.c:200 ast_db_get: Unable to find key '08157997950' in family 'ansage' [Dec 1 23:10:14] -- DBget: Value not found in database. [Dec 1 23:10:14] DEBUG[13937]: pbx.c:1589 pbx_substitute_variables_helper_full: Expression result is '0' [Dec 1 23:10:14] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'GotoIf' [Dec 1 23:10:14] -- Executing GotoIf("CAPI/ISDN1/669678-0", "0?3:6") in new stack [Dec 1 23:10:14] -- Goto (capi-in,669678,6) [Dec 1 23:10:14] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'DBput' [Dec 1 23:10:14] -- Executing DBput("CAPI/ISDN1/669678-0", "redir/IAX2/wolfgang=CAPI/ISDN1/669678-0") in new stack [Dec 1 23:10:14] WARNING[13937]: app_db.c:179 put_exec: This application has been deprecated, please use the ${DB(family/key)} function instead. [Dec 1 23:10:14] -- DBput: family=redir, key=IAX2/wolfgang, value=CAPI/ISDN1/669678-0 [Dec 1 23:10:15] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' [Dec 1 23:10:15] -- Executing Dial("CAPI/ISDN1/669678-0", "IAX2/wolfgang/08157997950|60|tr") in new stack [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-7. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-6. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-2. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DBGETSTATUS. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-1. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable CALLEDTON. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable BCHANNELINFO. [Dec 1 23:10:15] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable TRANSFERCAPABILITY. [Dec 1 23:10:15] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:15] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:15] DEBUG[13904]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'IAX2/wolfgang-8' [Dec 1 23:10:15] -- Called wolfgang/08157997950 [Dec 1 23:10:15] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 6 (Ringing) [Dec 1 23:10:15] DEBUG[13938]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:15] -- Call accepted by 192.168.2.3 (format alaw) [Dec 1 23:10:15] -- Format for call is alaw [Dec 1 23:10:15] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to write format alaw [Dec 1 23:10:15] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to read format alaw [Dec 1 23:10:15] -- IAX2/wolfgang-8 is ringing [Dec 1 23:10:17] -- IAX2/wolfgang-8 answered CAPI/ISDN1/669678-0 [Dec 1 23:10:17] == ISDN1: Answering for 669678 [Dec 1 23:10:17] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:17] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:17] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 2 (In use) [Dec 1 23:10:17] DEBUG[13941]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:17] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for CAPI/ISDN1/669678 - state 2 (In use) [Dec 1 23:10:17] DEBUG[13942]: app_queue.c:500 changethread: Device 'CAPI/ISDN1/669678' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:17] DEBUG[13916]: chan_iax2.c:6781 socket_read: Ooh, voice format changed to 8 [Dec 1 23:10:17] DEBUG[13937]: channel.c:2009 ast_read: Dropping duplicate answer! [Dec 1 23:10:20] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:20] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:22] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:22] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:22] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:22] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:22] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:22] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:22] DEBUG[13945]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:22] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 9, having received INVAL [Dec 1 23:10:22] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 9 [Dec 1 23:10:25] DEBUG[13916]: chan_iax2.c:7751 iax2_do_register: Allocate call number [Dec 1 23:10:25] DEBUG[13916]: chan_iax2.c:7757 iax2_do_register: Registration created on call 10 [Dec 1 23:10:25] -- Registered IAX2 to '192.246.69.186', who sees us as 84.153.209.108:4569 with no messages waiting [Dec 1 23:10:26] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'login' [Dec 1 23:10:26] == Parsing '/etc/asterisk/manager.conf': [Dec 1 23:10:26] DEBUG[13946]: config.c:595 config_text_file_load: Parsing /etc/asterisk/manager.conf [Dec 1 23:10:26] Found [Dec 1 23:10:26] WARNING[13946]: acl.c:177 ast_append_ha: 24,127.0.0.1 is not a valid netmask [Dec 1 23:10:26] == Manager 'wolfgang' logged on from 192.168.2.21 [Dec 1 23:10:26] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'status' [Dec 1 23:10:26] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'Redirect' [Dec 1 23:10:26] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for AsyncGoto/IAX2/wolfgang - state 4 (Invalid) [Dec 1 23:10:26] DEBUG[13947]: app_queue.c:500 changethread: Device 'AsyncGoto/IAX2/wolfgang' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 1 23:10:26] DEBUG[13946]: channel.c:2834 ast_channel_masquerade: Planning to masquerade channel IAX2/wolfgang-8 into the structure of AsyncGoto/IAX2/wolfgang-8 [Dec 1 23:10:26] DEBUG[13946]: channel.c:2847 ast_channel_masquerade: Done planning to masquerade channel IAX2/wolfgang-8 into the structure of AsyncGoto/IAX2/wolfgang-8 [Dec 1 23:10:26] DEBUG[13946]: channel.c:2961 ast_do_masquerade: Actually Masquerading IAX2/wolfgang-8(6) into the structure of AsyncGoto/IAX2/wolfgang-8(6) [Dec 1 23:10:26] DEBUG[13946]: channel.c:2972 ast_do_masquerade: Got clone lock for masquerade on 'IAX2/wolfgang-8' at 0x81a95a4 [Dec 1 23:10:26] DEBUG[13946]: channel.c:3154 ast_do_masquerade: Putting channel IAX2/wolfgang-8 in 8/8 formats [Dec 1 23:10:26] DEBUG[13946]: channel.c:3189 ast_do_masquerade: Released clone lock on 'AsyncGoto/IAX2/wolfgang-8' [Dec 1 23:10:26] DEBUG[13946]: channel.c:3198 ast_do_masquerade: Done Masquerading IAX2/wolfgang-8 (6) [Dec 1 23:10:26] DEBUG[13948]: pbx.c:1677 pbx_extension_helper: Launching 'MeetMe' [Dec 1 23:10:26] -- Executing MeetMe("IAX2/wolfgang-8", "850") in new stack [Dec 1 23:10:26] == Parsing '/etc/asterisk/meetme.conf': [Dec 1 23:10:26] DEBUG[13948]: config.c:595 config_text_file_load: Parsing /etc/asterisk/meetme.conf [Dec 1 23:10:26] Found [Dec 1 23:10:26] DEBUG[13948]: chan_zap.c:7575 zt_request: Using channel -2 [Dec 1 23:10:26] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for Zap/pseudo - state 2 (In use) [Dec 1 23:10:26] DEBUG[13949]: app_queue.c:500 changethread: Device 'Zap/pseudo' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:26] DEBUG[13946]: channel.c:1150 ast_softhangup_nolock: Soft-Hanging up channel 'CAPI/ISDN1/669678-0' [Dec 1 23:10:26] DEBUG[13937]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: AsyncGoto/IAX2/wolfgang-8 [Dec 1 23:10:26] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'Redirect' [Dec 1 23:10:26] DEBUG[13946]: channel.c:1150 ast_softhangup_nolock: Soft-Hanging up channel 'IAX2/wolfgang-8' [Dec 1 23:10:26] DEBUG[13937]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels CAPI/ISDN1/669678-0 and AsyncGoto/IAX2/wolfgang-8 [Dec 1 23:10:26] DEBUG[13937]: channel.c:1378 ast_hangup: Hanging up zombie 'AsyncGoto/IAX2/wolfgang-8' [Dec 1 23:10:26] DEBUG[13937]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Dec 1 23:10:26] DEBUG[13937]: pbx.c:2316 __ast_pbx_run: Spawn extension (default,850,0) exited non-zero on 'CAPI/ISDN1/669678-0' [Dec 1 23:10:26] == Spawn extension (default, 850, 0) exited non-zero on 'CAPI/ISDN1/669678-0' [Dec 1 23:10:26] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'MeetMe' [Dec 1 23:10:26] -- Executing MeetMe("CAPI/ISDN1/669678-0", "850") in new stack [Dec 1 23:10:26] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for AsyncGoto/IAX2/wolfgang - state 4 (Invalid) [Dec 1 23:10:26] DEBUG[13950]: app_queue.c:500 changethread: Device 'AsyncGoto/IAX2/wolfgang' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Dec 1 23:10:26] -- Created MeetMe conference 1023 for conference '850' [Dec 1 23:10:26] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to write format gsm [Dec 1 23:10:26] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 160 sample intervals [Dec 1 23:10:26] -- Playing 'conf-onlyperson' (language 'de') [Dec 1 23:10:26] DEBUG[13948]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to write format slin [Dec 1 23:10:26] DEBUG[13948]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to read format slin [Dec 1 23:10:26] DEBUG[13948]: app_meetme.c:1077 conf_run: Placed channel IAX2/wolfgang-8 in ZAP conf 1023 [Dec 1 23:10:26] DEBUG[13948]: pbx.c:2316 __ast_pbx_run: Spawn extension (default,8*50,0) exited non-zero on 'IAX2/wolfgang-8' [Dec 1 23:10:26] == Spawn extension (default, 8*50, 0) exited non-zero on 'IAX2/wolfgang-8' [Dec 1 23:10:26] DEBUG[13948]: pbx.c:1677 pbx_extension_helper: Launching 'MeetMe' [Dec 1 23:10:26] -- Executing MeetMe("IAX2/wolfgang-8", "850|Ax") in new stack [Dec 1 23:10:26] DEBUG[13948]: app_meetme.c:1077 conf_run: Placed channel IAX2/wolfgang-8 in ZAP conf 1023 [Dec 1 23:10:29] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'Command' [Dec 1 23:10:29] DEBUG[13946]: app_meetme.c:642 conf_cmd: Cmdline: 850|k|2 [Dec 1 23:10:29] DEBUG[13948]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to write format gsm [Dec 1 23:10:29] DEBUG[13948]: channel.c:1761 ast_settimeout: Scheduling timer at 160 sample intervals [Dec 1 23:10:29] -- Playing 'conf-kicked' (language 'de') [Dec 1 23:10:29] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:29] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:29] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to write format alaw [Dec 1 23:10:29] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to write format slin [Dec 1 23:10:29] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to read format slin [Dec 1 23:10:29] DEBUG[13937]: app_meetme.c:1077 conf_run: Placed channel CAPI/ISDN1/669678-0 in ZAP conf 1023 [Dec 1 23:10:30] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:31] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.3 with 192.168.2.0 [Dec 1 23:10:31] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:31] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:31] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 2 (In use) [Dec 1 23:10:31] DEBUG[13952]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:31] DEBUG[13948]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:31] DEBUG[13948]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:31] DEBUG[13948]: channel.c:2409 set_format: Set channel IAX2/wolfgang-8 to write format slin [Dec 1 23:10:31] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.3 with 192.168.2.0 [Dec 1 23:10:31] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:31] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:31] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 2 (In use) [Dec 1 23:10:31] DEBUG[13953]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:32] DEBUG[13946]: manager.c:1249 process_message: Manager received command 'Originate' [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:32] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 6 (Ringing) [Dec 1 23:10:32] DEBUG[13954]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:32] -- Call accepted by 192.168.2.3 (format ulaw) [Dec 1 23:10:32] -- Format for call is ulaw [Dec 1 23:10:32] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-12 to write format ulaw [Dec 1 23:10:32] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-12 to read format ulaw [Dec 1 23:10:32] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:32] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:32] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=4 [Dec 1 23:10:32] WARNING[13948]: pbx.c:2404 __ast_pbx_run: Invalid extension '#', but no rule 'i' in context 'default' [Dec 1 23:10:32] DEBUG[13948]: channel.c:1373 ast_hangup: Hanging up channel 'IAX2/wolfgang-8' [Dec 1 23:10:32] DEBUG[13948]: chan_iax2.c:3104 iax2_hangup: We're hanging up IAX2/wolfgang-8 now... [Dec 1 23:10:32] -- Hungup 'IAX2/wolfgang-8' [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:32] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 6 (Ringing) [Dec 1 23:10:32] DEBUG[13955]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:32] > Channel IAX2/wolfgang-12 was answered. [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=48 [Dec 1 23:10:32] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 2 (In use) [Dec 1 23:10:32] DEBUG[13957]: app_queue.c:500 changethread: [Dec 1 23:10:32] == Manager 'wolfgang' logged off from 192.168.2.21 Device 'IAX2/wolfgang' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:32] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' [Dec 1 23:10:32] -- Executing Dial("IAX2/wolfgang-12", "IAX2/iaxcomm2|100|trg") in new stack [Dec 1 23:10:32] DEBUG[13956]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-default-45-1. [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:10:32] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:32] DEBUG[13904]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'IAX2/iaxcomm2-13' [Dec 1 23:10:32] -- Called iaxcomm2 [Dec 1 23:10:32] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 6 (Ringing) [Dec 1 23:10:32] DEBUG[13958]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:32] -- Call accepted by 192.168.2.8 (format gsm) [Dec 1 23:10:32] -- Format for call is gsm [Dec 1 23:10:32] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/iaxcomm2-13 to write format ulaw [Dec 1 23:10:32] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/iaxcomm2-13 to read format ulaw [Dec 1 23:10:32] -- IAX2/iaxcomm2-13 is ringing [Dec 1 23:10:32] DEBUG[13916]: chan_iax2.c:6781 socket_read: Ooh, voice format changed to 4 [Dec 1 23:10:35] DEBUG[13916]: chan_iax2.c:7257 socket_read: Peer lastms 25, historicms 24, maxms 2000 [Dec 1 23:10:36] -- IAX2/iaxcomm2-13 answered IAX2/wolfgang-12 [Dec 1 23:10:36] DEBUG[13956]: channel.c:2409 set_format: Set channel IAX2/wolfgang-12 to read format slin [Dec 1 23:10:36] DEBUG[13956]: channel.c:2409 set_format: Set channel IAX2/iaxcomm2-13 to write format slin [Dec 1 23:10:36] DEBUG[13956]: channel.c:2409 set_format: Set channel IAX2/iaxcomm2-13 to read format slin [Dec 1 23:10:36] DEBUG[13956]: channel.c:2409 set_format: Set channel IAX2/wolfgang-12 to write format slin [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:36] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 2 (In use) [Dec 1 23:10:36] DEBUG[13959]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:36] DEBUG[13916]: chan_iax2.c:6670 socket_read: Packet arrived out of order (expecting 6, got 5) (frametype = 10, subclass = 10) [Dec 1 23:10:36] DEBUG[13916]: chan_iax2.c:6677 socket_read: Acking anyway [Dec 1 23:10:36] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.8 with 192.168.2.0 [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:36] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 2 (In use) [Dec 1 23:10:36] DEBUG[13960]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:36] DEBUG[13916]: acl.c:211 ast_apply_ha: ##### Testing 192.168.2.8 with 192.168.2.0 [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:10:36] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:36] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 2 (In use) [Dec 1 23:10:36] DEBUG[13961]: app_queue.c:500 changethread: Device 'IAX2/iaxcomm2' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:50] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:50] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:50] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:50] DEBUG[13962]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:50] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 3, having received INVAL [Dec 1 23:10:50] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 3 [Dec 1 23:10:52] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:10:52] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:52] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:10:52] DEBUG[13963]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:10:52] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 4, having received INVAL [Dec 1 23:10:52] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 4 [Dec 1 23:10:54] DEBUG[13916]: chan_iax2.c:7074 socket_read: Immediately destroying 13, having received hangup [Dec 1 23:10:54] DEBUG[13956]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: IAX2/iaxcomm2-13 [Dec 1 23:10:54] DEBUG[13956]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels IAX2/wolfgang-12 and IAX2/iaxcomm2-13 [Dec 1 23:10:54] DEBUG[13956]: channel.c:1373 ast_hangup: Hanging up channel 'IAX2/iaxcomm2-13' [Dec 1 23:10:54] DEBUG[13956]: chan_iax2.c:3104 iax2_hangup: We're hanging up IAX2/iaxcomm2-13 now... [Dec 1 23:10:54] DEBUG[13956]: chan_iax2.c:3114 iax2_hangup: Really destroying IAX2/iaxcomm2-13 now... [Dec 1 23:10:54] -- Hungup 'IAX2/iaxcomm2-13' [Dec 1 23:10:54] DEBUG[13956]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'Cut' [Dec 1 23:10:54] -- Executing Cut("IAX2/wolfgang-12", "redirchan=CHANNEL|-|1") in new stack [Dec 1 23:10:54] WARNING[13956]: app_cut.c:320 cut_exec: The application Cut is deprecated. Please use the CUT() function instead. [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'DBget' [Dec 1 23:10:54] -- Executing DBget("IAX2/wolfgang-12", "redir=redir/IAX2/wolfgang") in new stack [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device iaxcomm2 [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of iaxcomm2? addr=134392000, defaddr=0 maxms=0, lastms=0 [Dec 1 23:10:54] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/iaxcomm2 - state 1 (Not in use) [Dec 1 23:10:54] DEBUG[13964]: app_queue.c:500 changethread: [Dec 1 23:10:54] -- DBget: varname=redir, family=redir, key=IAX2/wolfgang [Dec 1 23:10:54] -- DBget: set variable redir to CAPI/ISDN1/669678-0 Device 'IAX2/iaxcomm2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'NoOp' [Dec 1 23:10:54] -- Executing NoOp("IAX2/wolfgang-12", "ANSWER") in new stack [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1589 pbx_substitute_variables_helper_full: Expression result is '1' [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'GotoIf' [Dec 1 23:10:54] -- Executing GotoIf("IAX2/wolfgang-12", "1?6:7") in new stack [Dec 1 23:10:54] -- Goto (default,45,6) [Dec 1 23:10:54] DEBUG[13956]: pbx.c:1677 pbx_extension_helper: Launching 'AsyncGoto' [Dec 1 23:10:54] -- Executing AsyncGoto("IAX2/wolfgang-12", "CAPI/ISDN1/669678-0|default|46") in new stack [Dec 1 23:10:54] DEBUG[13956]: app_asyncgoto.c:95 asyncgoto_exec: Attempting async goto (CAPI/ISDN1/669678-0) to default,46,1 [Dec 1 23:10:54] DEBUG[13956]: channel.c:1150 ast_softhangup_nolock: Soft-Hanging up channel 'CAPI/ISDN1/669678-0' [Dec 1 23:10:54] DEBUG[13937]: channel.c:1373 ast_hangup: Hanging up channel 'Zap/pseudo-1958171140' [Dec 1 23:10:54] DEBUG[13937]: chan_zap.c:2309 zt_hangup: zt_hangup(Zap/pseudo-1958171140) [Dec 1 23:10:54] DEBUG[13937]: chan_zap.c:2342 zt_hangup: Hangup: channel: -2 index = 0, normal = 32, callwait = -1, thirdcall = -1 [Dec 1 23:10:54] DEBUG[13937]: chan_zap.c:2782 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/pseudo-1958171140 [Dec 1 23:10:54] DEBUG[13937]: chan_zap.c:1372 update_conf: Updated conferencing on -2, with 0 conference users [Dec 1 23:10:54] -- Hungup 'Zap/pseudo-1958171140' [Dec 1 23:10:54] DEBUG[13937]: pbx.c:2316 __ast_pbx_run: Spawn extension (default,46,0) exited non-zero on 'CAPI/ISDN1/669678-0' [Dec 1 23:10:54] == Spawn extension (default, 46, 0) exited non-zero on 'CAPI/ISDN1/669678-0' [Dec 1 23:10:54] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'NoOp' [Dec 1 23:10:54] -- Executing NoOp("CAPI/ISDN1/669678-0", "08157997950") in new stack [Dec 1 23:10:54] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'DBput' [Dec 1 23:10:54] -- Executing DBput("CAPI/ISDN1/669678-0", "partner/46=CAPI/ISDN1/669678-0") in new stack [Dec 1 23:10:54] -- DBput: family=partner, key=46, value=CAPI/ISDN1/669678-0 [Dec 1 23:10:54] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for Zap/pseudo - state 0 (Unknown) [Dec 1 23:10:54] DEBUG[13965]: app_queue.c:500 changethread: Device 'Zap/pseudo' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 1 23:10:54] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' [Dec 1 23:10:54] -- Executing Dial("CAPI/ISDN1/669678-0", "IAX2/wolfgang|180|trg") in new stack [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-default-46-3. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-default-46-2. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-default-46-1. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable MEETMESECS. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-default-850-1. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DIALSTATUS. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DIALEDTIME. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable ANSWEREDTIME. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable BRIDGEPEER. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DIALEDPEERNUMBER. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DIALEDPEERNAME. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-7. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-6. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-2. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable DBGETSTATUS. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-capi-in-669678-1. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable CALLEDTON. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable BCHANNELINFO. [Dec 1 23:10:54] DEBUG[13937]: channel.c:2902 ast_channel_inherit_variables: Not copying variable TRANSFERCAPABILITY. [Dec 1 23:10:54] -- Called wolfgang [Dec 1 23:10:54] DEBUG[13937]: channel.c:2088 ast_indicate: Driver for channel 'CAPI/ISDN1/669678-0' does not support indication 3, emulating it [Dec 1 23:10:54] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 160 sample intervals [Dec 1 23:10:54] DEBUG[13937]: channel.c:2027 ast_read: Generator got voice, switching to phase locked mode [Dec 1 23:10:54] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=25 [Dec 1 23:10:54] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 6 (Ringing) [Dec 1 23:10:54] DEBUG[13966]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:54] -- Call accepted by 192.168.2.3 (format alaw) [Dec 1 23:10:54] -- Format for call is alaw [Dec 1 23:10:54] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-15 to write format alaw [Dec 1 23:10:54] DEBUG[13916]: channel.c:2409 set_format: Set channel IAX2/wolfgang-15 to read format alaw [Dec 1 23:10:54] -- IAX2/wolfgang-15 is ringing [Dec 1 23:10:54] WARNING[13956]: pbx.c:2404 __ast_pbx_run: Invalid extension '#', but no rule 'i' in context 'default' [Dec 1 23:10:54] DEBUG[13956]: channel.c:1373 ast_hangup: Hanging up channel 'IAX2/wolfgang-12' [Dec 1 23:10:54] DEBUG[13956]: chan_iax2.c:3104 iax2_hangup: We're hanging up IAX2/wolfgang-12 now... [Dec 1 23:10:54] -- Hungup 'IAX2/wolfgang-12' [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=25 [Dec 1 23:10:54] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 6 (Ringing) [Dec 1 23:10:54] DEBUG[13967]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:10:54] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=25 [Dec 1 23:10:54] DEBUG[13904]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'IAX2/wolfgang-15' [Dec 1 23:10:54] -- IAX2/wolfgang-15 answered CAPI/ISDN1/669678-0 [Dec 1 23:10:54] DEBUG[13937]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals [Dec 1 23:10:54] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to read format alaw [Dec 1 23:10:54] DEBUG[13937]: channel.c:2409 set_format: Set channel CAPI/ISDN1/669678-0 to write format alaw [Dec 1 23:10:54] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 2 (In use) [Dec 1 23:10:54] DEBUG[13968]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 1 23:10:55] DEBUG[13916]: chan_iax2.c:6781 socket_read: Ooh, voice format changed to 8 [Dec 1 23:11:00] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:02] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:02] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:11:02] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:11:02] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:11:02] DEBUG[13969]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:11:02] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 5, having received INVAL [Dec 1 23:11:02] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 5 [Dec 1 23:11:02] DEBUG[13916]: chan_iax2.c:7074 socket_read: Immediately destroying 15, having received hangup [Dec 1 23:11:02] DEBUG[13937]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: IAX2/wolfgang-15 [Dec 1 23:11:02] DEBUG[13937]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels CAPI/ISDN1/669678-0 and IAX2/wolfgang-15 [Dec 1 23:11:02] DEBUG[13937]: channel.c:1373 ast_hangup: Hanging up channel 'IAX2/wolfgang-15' [Dec 1 23:11:02] DEBUG[13937]: chan_iax2.c:3104 iax2_hangup: We're hanging up IAX2/wolfgang-15 now... [Dec 1 23:11:02] DEBUG[13937]: chan_iax2.c:3114 iax2_hangup: Really destroying IAX2/wolfgang-15 now... [Dec 1 23:11:02] -- Hungup 'IAX2/wolfgang-15' [Dec 1 23:11:02] DEBUG[13937]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Dec 1 23:11:02] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'NoOp' [Dec 1 23:11:02] -- Executing NoOp("CAPI/ISDN1/669678-0", "finished") in new stack [Dec 1 23:11:02] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'DBget' [Dec 1 23:11:02] -- Executing DBget("CAPI/ISDN1/669678-0", "redir/CAPI/ISDN1/669678-0") in new stack [Dec 1 23:11:02] DEBUG[13937]: app_db.c:274 get_exec: Ignoring, no parameters [Dec 1 23:11:02] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device wolfgang [Dec 1 23:11:02] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of wolfgang? addr=50505920, defaddr=0 maxms=2000, lastms=25 [Dec 1 23:11:02] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/wolfgang - state 1 (Not in use) [Dec 1 23:11:02] DEBUG[13970]: app_queue.c:500 changethread: Device 'IAX2/wolfgang' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:11:02] DEBUG[13937]: pbx.c:1677 pbx_extension_helper: Launching 'NoOp' [Dec 1 23:11:02] -- Executing NoOp("CAPI/ISDN1/669678-0", "ANSWER") in new stack *CLI> st[Dec 1 23:11:10] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:10] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 op n[Dec 1 23:11:12] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:12] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:12] DEBUG[13916]: chan_iax2.c:4958 raw_hangup: Raw Hangup 192.168.56.1:4569, src=0, dst=2 [Dec 1 23:11:12] DEBUG[13904]: chan_iax2.c:9448 iax2_devicestate: Checking device state for device laden [Dec 1 23:11:12] DEBUG[13904]: chan_iax2.c:9456 iax2_devicestate: iax2_devicestate: Found peer. What's device state of laden? addr=0, defaddr=0 maxms=0, lastms=0 [Dec 1 23:11:12] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for IAX2/laden - state 5 (Unavailable) [Dec 1 23:11:12] DEBUG[13971]: app_queue.c:500 changethread: Device 'IAX2/laden' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Dec 1 23:11:12] DEBUG[13916]: chan_iax2.c:7484 socket_read: Immediately destroying 1, having received INVAL [Dec 1 23:11:12] DEBUG[13916]: chan_iax2.c:7487 socket_read: Destroying call 1 o[Dec 1 23:11:13] WARNING[13937]: pbx.c:2415 __ast_pbx_run: Timeout, but no rule 't' in context 'default' [Dec 1 23:11:13] DEBUG[13937]: channel.c:1373 ast_hangup: Hanging up channel 'CAPI/ISDN1/669678-0' [Dec 1 23:11:13] == ISDN1: CAPI Hangingup [Dec 1 23:11:13] DEBUG[13904]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'CAPI/ISDN1/669678-0' [Dec 1 23:11:13] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for CAPI/ISDN1/669678 - state 1 (Not in use) [Dec 1 23:11:13] DEBUG[13972]: app_queue.c:500 changethread: Device 'CAPI/ISDN1/669678' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 1 23:11:13] DEBUG[13904]: devicestate.c:187 do_state_change: Changing state for CAPI/ISDN1/669678 - state 1 (Not in use) [Dec 1 23:11:13] DEBUG[13973]: app_queue.c:500 changethread: Device 'CAPI/ISDN1/669678' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. w  [Dec 1 23:11:14] Beginning asterisk shutdown.... [Dec 1 23:11:14] Executing last minute cleanups [Dec 1 23:11:14] == Destroying musiconhold processes [Dec 1 23:11:14] DEBUG[13898]: res_musiconhold.c:1091 ast_moh_destroy: killing 13908! [Dec 1 23:11:15] DEBUG[13898]: res_musiconhold.c:1106 ast_moh_destroy: mpg123 pid 13908 and child died after 4161024 bytes read [Dec 1 23:11:15] DEBUG[13898]: res_musiconhold.c:1091 ast_moh_destroy: killing 13906! [Dec 1 23:11:15] DEBUG[13898]: res_musiconhold.c:1106 ast_moh_destroy: mpg123 pid 13906 and child died after 8190976 bytes read [Dec 1 23:11:15] Asterisk cleanly ending (0). [Dec 1 23:11:15] DEBUG[13898]: asterisk.c:900 quit_handler: Asterisk ending (0).