Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf [Dec 19 14:49:45] == Parsing '/etc/asterisk/asterisk.conf': [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Parsing '/etc/asterisk/extconfig.conf': [Dec 19 14:49:45] Found [Dec 19 14:49:45] Asterisk SVN-branch-1.2-r48552M, Copyright (C) 1999 - 2006 Digium, Inc. and others. [Dec 19 14:49:45] Created by Mark Spencer [Dec 19 14:49:45] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. [Dec 19 14:49:45] This is free software, with components licensed under the GNU General Public [Dec 19 14:49:45] License version 2 and other licenses; you are welcome to redistribute it under [Dec 19 14:49:45] certain conditions. Type 'show license' for details. [Dec 19 14:49:45] ========================================================================= [Dec 19 14:49:45] == Parsing '/etc/asterisk/logger.conf': Parsing /etc/asterisk/logger.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] Asterisk Event Logger Started /var/log/asterisk/event_log [Dec 19 14:49:45] == Parsing '/etc/asterisk/dnsmgr.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/dnsmgr.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] Asterisk Dynamic Loader loading preload modules: [Dec 19 14:49:45] == Parsing '/etc/asterisk/modules.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/modules.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Manager registered action Ping [Dec 19 14:49:45] == Manager registered action Events [Dec 19 14:49:45] == Manager registered action Logoff [Dec 19 14:49:45] == Manager registered action Hangup [Dec 19 14:49:45] == Manager registered action Status [Dec 19 14:49:45] == Manager registered action Setvar [Dec 19 14:49:45] == Manager registered action Getvar [Dec 19 14:49:45] == Manager registered action Redirect [Dec 19 14:49:45] == Manager registered action Originate [Dec 19 14:49:45] == Manager registered action Command [Dec 19 14:49:45] == Manager registered action ExtensionState [Dec 19 14:49:45] == Manager registered action AbsoluteTimeout [Dec 19 14:49:45] == Manager registered action MailboxStatus [Dec 19 14:49:45] == Manager registered action MailboxCount [Dec 19 14:49:45] == Manager registered action ListCommands [Dec 19 14:49:45] == Parsing '/etc/asterisk/manager.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/manager.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Parsing '/etc/asterisk/cdr.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] NOTICE[9880]: cdr.c:1192 do_reload: CDR simple logging enabled. [Dec 19 14:49:45] == Parsing '/etc/asterisk/rtp.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/rtp.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == RTP Allocating from port range 20000 -> 60000 [Dec 19 14:49:45] Asterisk PBX Core Initializing [Dec 19 14:49:45] Registering builtin applications: [Dec 19 14:49:45] [AbsoluteTimeout] [Dec 19 14:49:45] == Registered application 'AbsoluteTimeout' [Dec 19 14:49:45] [Answer] [Dec 19 14:49:45] == Registered application 'Answer' [Dec 19 14:49:45] [BackGround] [Dec 19 14:49:45] == Registered application 'BackGround' [Dec 19 14:49:45] [Busy] [Dec 19 14:49:45] == Registered application 'Busy' [Dec 19 14:49:45] [Congestion] [Dec 19 14:49:45] == Registered application 'Congestion' [Dec 19 14:49:45] [DigitTimeout] [Dec 19 14:49:45] == Registered application 'DigitTimeout' [Dec 19 14:49:45] [Goto] [Dec 19 14:49:45] == Registered application 'Goto' [Dec 19 14:49:45] [GotoIf] [Dec 19 14:49:45] == Registered application 'GotoIf' [Dec 19 14:49:45] [GotoIfTime] [Dec 19 14:49:45] == Registered application 'GotoIfTime' [Dec 19 14:49:45] [ExecIfTime] [Dec 19 14:49:45] == Registered application 'ExecIfTime' [Dec 19 14:49:45] [Hangup] [Dec 19 14:49:45] == Registered application 'Hangup' [Dec 19 14:49:45] [NoOp] [Dec 19 14:49:45] == Registered application 'NoOp' [Dec 19 14:49:45] [Progress] [Dec 19 14:49:45] == Registered application 'Progress' [Dec 19 14:49:45] [ResetCDR] [Dec 19 14:49:45] == Registered application 'ResetCDR' [Dec 19 14:49:45] [ResponseTimeout] [Dec 19 14:49:45] == Registered application 'ResponseTimeout' [Dec 19 14:49:45] [Ringing] [Dec 19 14:49:45] == Registered application 'Ringing' [Dec 19 14:49:45] [SayNumber] [Dec 19 14:49:45] == Registered application 'SayNumber' [Dec 19 14:49:45] [SayDigits] [Dec 19 14:49:45] == Registered application 'SayDigits' [Dec 19 14:49:45] [SayAlpha] [Dec 19 14:49:45] == Registered application 'SayAlpha' [Dec 19 14:49:45] [SayPhonetic] [Dec 19 14:49:45] == Registered application 'SayPhonetic' [Dec 19 14:49:45] [SetAccount] [Dec 19 14:49:45] == Registered application 'SetAccount' [Dec 19 14:49:45] [SetAMAFlags] [Dec 19 14:49:45] == Registered application 'SetAMAFlags' [Dec 19 14:49:45] [SetGlobalVar] [Dec 19 14:49:45] == Registered application 'SetGlobalVar' [Dec 19 14:49:45] [SetLanguage] [Dec 19 14:49:45] == Registered application 'SetLanguage' [Dec 19 14:49:45] [Set] [Dec 19 14:49:45] == Registered application 'Set' [Dec 19 14:49:45] [SetVar] [Dec 19 14:49:45] == Registered application 'SetVar' [Dec 19 14:49:45] [ImportVar] [Dec 19 14:49:45] == Registered application 'ImportVar' [Dec 19 14:49:45] [Wait] [Dec 19 14:49:45] == Registered application 'Wait' [Dec 19 14:49:45] [WaitExten] [Dec 19 14:49:45] == Registered application 'WaitExten' [Dec 19 14:49:45] == Manager registered action DBGet [Dec 19 14:49:45] == Manager registered action DBPut [Dec 19 14:49:45] == Parsing '/etc/asterisk/enum.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/enum.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] Asterisk Dynamic Loader Starting: [Dec 19 14:49:45] == Parsing '/etc/asterisk/modules.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/modules.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] [res_indications.so][Dec 19 14:49:45] => (Indications Configuration) [Dec 19 14:49:45] == Parsing '/etc/asterisk/indications.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/indications.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- Registered indication country 'at' [Dec 19 14:49:45] -- Registered indication country 'au' [Dec 19 14:49:45] -- Registered indication country 'br' [Dec 19 14:49:45] -- Registered indication country 'be' [Dec 19 14:49:45] -- Registered indication country 'ch' [Dec 19 14:49:45] -- Registered indication country 'cl' [Dec 19 14:49:45] -- Registered indication country 'cn' [Dec 19 14:49:45] -- Registered indication country 'cz' [Dec 19 14:49:45] -- Registered indication country 'de' [Dec 19 14:49:45] -- Registered indication country 'dk' [Dec 19 14:49:45] -- Registered indication country 'ee' [Dec 19 14:49:45] -- Registered indication country 'es' [Dec 19 14:49:45] -- Registered indication country 'fi' [Dec 19 14:49:45] -- Registered indication country 'fr' [Dec 19 14:49:45] -- Registered indication country 'gr' [Dec 19 14:49:45] -- Registered indication country 'hu' [Dec 19 14:49:45] -- Registered indication country 'it' [Dec 19 14:49:45] -- Registered indication country 'lt' [Dec 19 14:49:45] -- Registered indication country 'mx' [Dec 19 14:49:45] -- Registered indication country 'nl' [Dec 19 14:49:45] -- Registered indication country 'no' [Dec 19 14:49:45] -- Registered indication country 'nz' [Dec 19 14:49:45] -- Registered indication country 'pl' [Dec 19 14:49:45] -- Registered indication country 'pt' [Dec 19 14:49:45] -- Registered indication country 'ru' [Dec 19 14:49:45] -- Registered indication country 'se' [Dec 19 14:49:45] -- Registered indication country 'sg' [Dec 19 14:49:45] -- Registered indication country 'uk' [Dec 19 14:49:45] -- Registered indication country 'us' [Dec 19 14:49:45] -- Registered indication country 'us-o' [Dec 19 14:49:45] -- Registered indication country 'tw' [Dec 19 14:49:45] -- Registered indication country 've' [Dec 19 14:49:45] -- Registered indication country 'za' [Dec 19 14:49:45] -- Setting default indication country to 'de' [Dec 19 14:49:45] == Registered application 'PlayTones' [Dec 19 14:49:45] == Registered application 'StopPlayTones' [Dec 19 14:49:45] [res_monitor.so][Dec 19 14:49:45] => (Call Monitoring Resource) [Dec 19 14:49:45] == Registered application 'Monitor' [Dec 19 14:49:45] == Registered application 'StopMonitor' [Dec 19 14:49:45] == Registered application 'ChangeMonitor' [Dec 19 14:49:45] == Manager registered action Monitor [Dec 19 14:49:45] == Manager registered action StopMonitor [Dec 19 14:49:45] == Manager registered action ChangeMonitor [Dec 19 14:49:45] [res_adsi.so][Dec 19 14:49:45] => (ADSI Resource) [Dec 19 14:49:45] == Parsing '/etc/asterisk/adsi.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/adsi.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] [res_agi.so][Dec 19 14:49:45] => (Asterisk Gateway Interface (AGI)) [Dec 19 14:49:45] == Registered application 'DeadAGI' [Dec 19 14:49:45] == Registered application 'EAGI' [Dec 19 14:49:45] == Registered application 'AGI' [Dec 19 14:49:45] [res_features.so][Dec 19 14:49:45] => (Call Features Resource) [Dec 19 14:49:45] == Parsing '/etc/asterisk/features.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/features.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'parkedcalls' [Dec 19 14:49:45] -- Registered extension context 'parkedcalls' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '700' priority 1 to parkedcalls [Dec 19 14:49:45] -- Added extension '700' priority 1 to parkedcalls [Dec 19 14:49:45] == Registered application 'ParkedCall' [Dec 19 14:49:45] == Registered application 'Park' [Dec 19 14:49:45] == Manager registered action ParkedCalls [Dec 19 14:49:45] [res_crypto.so][Dec 19 14:49:45] => (Cryptographic Digital Signatures) [Dec 19 14:49:45] -- Loaded PUBLIC key 'iaxtel' [Dec 19 14:49:45] DEBUG[9880]: res_crypto.c:257 try_load_key: Key 'iaxtel' loaded OK [Dec 19 14:49:45] -- Loaded PUBLIC key 'freeworlddialup' [Dec 19 14:49:45] DEBUG[9880]: res_crypto.c:257 try_load_key: Key 'freeworlddialup' loaded OK [Dec 19 14:49:45] [skipping res_musiconhold.so] [Dec 19 14:49:45] [pbx_config.so][Dec 19 14:49:45] => (Text Extension Configuration) [Dec 19 14:49:45] == Parsing '/etc/asterisk/extensions.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/extensions.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'intern' [Dec 19 14:49:45] -- Registered extension context 'intern' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '_X.' priority 1 to intern [Dec 19 14:49:45] -- Added extension '_X.' priority 1 to intern [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '_X.' priority 2 to intern [Dec 19 14:49:45] -- Added extension '_X.' priority 2 to intern [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'intern-post' [Dec 19 14:49:45] -- Registered extension context 'intern-post' [Dec 19 14:49:45] -- Including context 'das-out' in context 'intern-post' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'sip-out' [Dec 19 14:49:45] -- Registered extension context 'sip-out' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '_7.' priority 1 to sip-out [Dec 19 14:49:45] -- Added extension '_7.' priority 1 to sip-out [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'dialnumber' [Dec 19 14:49:45] -- Registered extension context 'dialnumber' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '1001' priority 1 to dialnumber [Dec 19 14:49:45] -- Added extension '1001' priority 1 to dialnumber [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '1001' priority 2 to dialnumber [Dec 19 14:49:45] -- Added extension '1001' priority 2 to dialnumber [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'macro-setprivacy' [Dec 19 14:49:45] -- Registered extension context 'macro-setprivacy' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-setprivacy [Dec 19 14:49:45] -- Added extension 's' priority 1 to macro-setprivacy [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-setprivacy [Dec 19 14:49:45] -- Added extension 's' priority 2 to macro-setprivacy [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'sip-incoming' [Dec 19 14:49:45] -- Registered extension context 'sip-incoming' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '_XX.' priority 1 to sip-incoming [Dec 19 14:49:45] -- Added extension '_XX.' priority 1 to sip-incoming [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '_XX.' priority 10 to sip-incoming [Dec 19 14:49:45] -- Added extension '_XX.' priority 10 to sip-incoming [Dec 19 14:49:45] WARNING[9880]: pbx.c:6444 ast_context_verify_includes: Context 'intern-post' tries includes nonexistent context 'das-out' [Dec 19 14:49:45] [pbx_spool.so][Dec 19 14:49:45] => (Outgoing Spool Support) [Dec 19 14:49:45] [pbx_dundi.so][Dec 19 14:49:45] => (Distributed Universal Number Discovery (DUNDi)) [Dec 19 14:49:45] == Parsing '/etc/asterisk/dundi.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/dundi.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: pbx_dundi.c:422 reset_global_eid: Seeding global EID '00:90:27:d1:50:df' from 'eth0' [Dec 19 14:49:45] == Using TOS bits 0 [Dec 19 14:49:45] == DUNDi Ready and Listening on 0.0.0.0 port 4520 [Dec 19 14:49:45] == Registered application 'DUNDiLookup' [Dec 19 14:49:45] == Registered custom function DUNDILOOKUP [Dec 19 14:49:45] [pbx_loopback.so][Dec 19 14:49:45] => (Loopback Switch) [Dec 19 14:49:45] [pbx_realtime.so][Dec 19 14:49:45] => (Realtime Switch) [Dec 19 14:49:45] [pbx_ael.so][Dec 19 14:49:45] => (Asterisk Extension Language Compiler) [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'macro-std-exten-ael' [Dec 19 14:49:45] -- Registered extension context 'macro-std-exten-ael' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 's' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 's' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 's' priority 3 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 's' priority 4 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 's' priority 5 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension 'a' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 'a' priority 1 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 'a' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] -- Added extension 'a' priority 2 to macro-std-exten-ael [Dec 19 14:49:45] DEBUG[9880]: pbx.c:3674 ast_context_create: Registered context 'ael-demo' [Dec 19 14:49:45] -- Registered extension context 'ael-demo' [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 3 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 4 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 5 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 6 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 6 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 8 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 8 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 9 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 9 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 10 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 10 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 11 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 11 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 12 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 12 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4828 ast_add_extension2: Added extension 's' priority 7 to ael-demo [Dec 19 14:49:45] -- Added extension 's' priority 7 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension '2' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '2' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '2' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension '2' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension '3' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '3' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '3' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension '3' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension '500' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '500' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension '500' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 3 to ael-demo [Dec 19 14:49:45] -- Added extension '500' priority 3 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 4 to ael-demo [Dec 19 14:49:45] -- Added extension '500' priority 4 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension '600' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '600' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension '600' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 3 to ael-demo [Dec 19 14:49:45] -- Added extension '600' priority 3 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 4 to ael-demo [Dec 19 14:49:45] -- Added extension '600' priority 4 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4876 ast_add_extension2: Added extension '_1234' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '_1234' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension '#' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension '#' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4842 ast_add_extension2: Added extension '#' priority 2 to ael-demo [Dec 19 14:49:45] -- Added extension '#' priority 2 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension 't' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension 't' priority 1 to ael-demo [Dec 19 14:49:45] DEBUG[9880]: pbx.c:4861 ast_add_extension2: Added extension 'i' priority 1 to ael-demo [Dec 19 14:49:45] -- Added extension 'i' priority 1 to ael-demo [Dec 19 14:49:45] WARNING[9880]: pbx.c:6444 ast_context_verify_includes: Context 'intern-post' tries includes nonexistent context 'das-out' [Dec 19 14:49:45] [pbx_functions.so][Dec 19 14:49:45] => (Builtin dialplan functions) [Dec 19 14:49:45] == Registered custom function MD5 [Dec 19 14:49:45] == Registered custom function CHECK_MD5 [Dec 19 14:49:45] == Registered custom function MATH [Dec 19 14:49:45] == Registered custom function GROUP_COUNT [Dec 19 14:49:45] == Registered custom function GROUP_MATCH_COUNT [Dec 19 14:49:45] == Registered custom function GROUP [Dec 19 14:49:45] == Registered custom function GROUP_LIST [Dec 19 14:49:45] == Registered custom function FIELDQTY [Dec 19 14:49:45] == Registered custom function REGEX [Dec 19 14:49:45] == Registered custom function LEN [Dec 19 14:49:45] == Registered custom function STRFTIME [Dec 19 14:49:45] == Registered custom function EVAL [Dec 19 14:49:45] == Registered custom function CDR [Dec 19 14:49:45] == Registered custom function ISNULL [Dec 19 14:49:45] == Registered custom function SET [Dec 19 14:49:45] == Registered custom function EXISTS [Dec 19 14:49:45] == Registered custom function IF [Dec 19 14:49:45] == Registered custom function IFTIME [Dec 19 14:49:45] == Registered custom function ENV [Dec 19 14:49:45] == Registered custom function DB [Dec 19 14:49:45] == Registered custom function DB_EXISTS [Dec 19 14:49:45] == Registered custom function TIMEOUT [Dec 19 14:49:45] == Registered custom function LANGUAGE [Dec 19 14:49:45] == Registered custom function MUSICCLASS [Dec 19 14:49:45] [chan_sip.so][Dec 19 14:49:45] => (Session Initiation Protocol (SIP)) [Dec 19 14:49:45] == Parsing '/etc/asterisk/sip.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/sip.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: chan_sip.c:12707 reload_config: Setting User Agent Name to Asterisk Test 01 [Dec 19 14:49:45] == SIP Listening on 0.0.0.0:5060 [Dec 19 14:49:45] == Using TOS bits 0 [Dec 19 14:49:45] == Parsing '/etc/asterisk/sip_notify.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) [Dec 19 14:49:45] == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) [Dec 19 14:49:45] == Registered application 'SIPDtmfMode' [Dec 19 14:49:45] == Registered application 'SIPAddHeader' [Dec 19 14:49:45] == Registered application 'SIPGetHeader' [Dec 19 14:49:45] == Registered custom function SIP_HEADER [Dec 19 14:49:45] == Registered custom function SIPPEER [Dec 19 14:49:45] == Registered custom function SIPCHANINFO [Dec 19 14:49:45] == Registered custom function CHECKSIPDOMAIN [Dec 19 14:49:45] == Manager registered action SIPpeers [Dec 19 14:49:45] == Manager registered action SIPshowpeer [Dec 19 14:49:45] [chan_agent.so][Dec 19 14:49:45] => (Agent Proxy Channel) [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Agent' (Call Agent Proxy Channel) [Dec 19 14:49:45] == Registered channel type 'Agent' (Call Agent Proxy Channel) [Dec 19 14:49:45] == Registered application 'AgentLogin' [Dec 19 14:49:45] == Registered application 'AgentCallbackLogin' [Dec 19 14:49:45] == Registered application 'AgentMonitorOutgoing' [Dec 19 14:49:45] == Manager registered action Agents [Dec 19 14:49:45] == Manager registered action AgentLogoff [Dec 19 14:49:45] == Manager registered action AgentCallbackLogin [Dec 19 14:49:45] == Parsing '/etc/asterisk/agents.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/agents.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] [chan_mgcp.so][Dec 19 14:49:45] => (Media Gateway Control Protocol (MGCP)) [Dec 19 14:49:45] == Parsing '/etc/asterisk/mgcp.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/mgcp.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == MGCP Listening on 0.0.0.0:2727 [Dec 19 14:49:45] == Using TOS bits 0 [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) [Dec 19 14:49:45] == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) [Dec 19 14:49:45] [chan_iax2.so][Dec 19 14:49:45] => (Inter Asterisk eXchange (Ver 2)) [Dec 19 14:49:45] == Registered custom function IAXPEER [Dec 19 14:49:45] == Registered application 'IAX2Provision' [Dec 19 14:49:45] == Manager registered action IAXpeers [Dec 19 14:49:45] == Manager registered action IAXnetstats [Dec 19 14:49:45] == Parsing '/etc/asterisk/iax.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/iax.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Using TOS bits 16 [Dec 19 14:49:45] == Binding IAX2 to default address 0.0.0.0:4569 [Dec 19 14:49:45] -- doing lookup for '216.207.245.47' [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Dec 19 14:49:45] == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Dec 19 14:49:45] == IAX Ready and Listening [Dec 19 14:49:45] == Loaded firmware 'iaxy.bin' [Dec 19 14:49:45] == Parsing '/etc/asterisk/iaxprov.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/iaxprov.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- Loaded provisioning template 'default' [Dec 19 14:49:45] [chan_local.so][Dec 19 14:49:45] => (Local Proxy Channel) [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Local' (Local Proxy Channel Driver) [Dec 19 14:49:45] == Registered channel type 'Local' (Local Proxy Channel Driver) [Dec 19 14:49:45] [chan_skinny.so][Dec 19 14:49:45] => (Skinny Client Control Protocol (Skinny)) [Dec 19 14:49:45] == Parsing '/etc/asterisk/skinny.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/skinny.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Skinny listening on 0.0.0.0:2000 [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) [Dec 19 14:49:45] == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) [Dec 19 14:49:45] [chan_features.so][Dec 19 14:49:45] => (Feature Proxy Channel) [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Feature' (Feature Proxy Channel Driver) [Dec 19 14:49:45] == Registered channel type 'Feature' (Feature Proxy Channel Driver) [Dec 19 14:49:45] [chan_oss.so][Dec 19 14:49:45] => (OSS Console Channel Driver) [Dec 19 14:49:45] == Parsing '/etc/asterisk/oss.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/oss.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Console' (OSS Console Channel Driver) [Dec 19 14:49:45] == Registered channel type 'Console' (OSS Console Channel Driver) [Dec 19 14:49:45] [chan_phone.so][Dec 19 14:49:45] => (Linux Telephony API Support) [Dec 19 14:49:45] == Parsing '/etc/asterisk/phone.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/phone.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: channel.c:344 ast_channel_register: Registered handler for 'Phone' (Standard Linux Telephony API Driver) [Dec 19 14:49:45] == Registered channel type 'Phone' (Standard Linux Telephony API Driver) [Dec 19 14:49:45] [skipping res_musiconhold.so] [Dec 19 14:49:45] [app_dial.so][Dec 19 14:49:45] => (Dialing Application) [Dec 19 14:49:45] == Registered application 'Dial' [Dec 19 14:49:45] == Registered application 'RetryDial' [Dec 19 14:49:45] [app_playback.so][Dec 19 14:49:45] => (Sound File Playback Application) [Dec 19 14:49:45] == Registered application 'Playback' [Dec 19 14:49:45] [app_voicemail.so][Dec 19 14:49:45] => (Comedian Mail (Voicemail System)) [Dec 19 14:49:45] == Registered application 'VoiceMail' [Dec 19 14:49:45] == Registered application 'VoiceMailMain' [Dec 19 14:49:45] == Registered application 'MailboxExists' [Dec 19 14:49:45] == Registered application 'VMAuthenticate' [Dec 19 14:49:45] == Parsing '/etc/asterisk/voicemail.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/voicemail.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6057 load_config: VM Review Option disabled globally [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6063 load_config: VM Operator break disabled globally [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6069 load_config: VM CID Info before msg disabled globally [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6081 load_config: ENVELOPE before msg enabled globally [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6087 load_config: Duration info before msg enabled globally [Dec 19 14:49:45] DEBUG[9880]: app_voicemail.c:6102 load_config: We are not going to skip to the next msg after save/delete [Dec 19 14:49:45] [app_directory.so][Dec 19 14:49:45] => (Extension Directory) [Dec 19 14:49:45] == Registered application 'Directory' [Dec 19 14:49:45] [app_mp3.so][Dec 19 14:49:45] => (Silly MP3 Application) [Dec 19 14:49:45] == Registered application 'MP3Player' [Dec 19 14:49:45] [app_system.so][Dec 19 14:49:45] => (Generic System() application) [Dec 19 14:49:45] == Registered application 'TrySystem' [Dec 19 14:49:45] == Registered application 'System' [Dec 19 14:49:45] [app_echo.so][Dec 19 14:49:45] => (Simple Echo Application) [Dec 19 14:49:45] == Registered application 'Echo' [Dec 19 14:49:45] [app_record.so][Dec 19 14:49:45] => (Trivial Record Application) [Dec 19 14:49:45] == Registered application 'Record' [Dec 19 14:49:45] [app_image.so][Dec 19 14:49:45] => (Image Transmission Application) [Dec 19 14:49:45] == Registered application 'SendImage' [Dec 19 14:49:45] [app_url.so][Dec 19 14:49:45] => (Send URL Applications) [Dec 19 14:49:45] == Registered application 'SendURL' [Dec 19 14:49:45] [app_disa.so][Dec 19 14:49:45] => (DISA (Direct Inward System Access) Application) [Dec 19 14:49:45] == Registered application 'DISA' [Dec 19 14:49:45] [app_adsiprog.so][Dec 19 14:49:45] => (Asterisk ADSI Programming Application) [Dec 19 14:49:45] == Registered application 'ADSIProg' [Dec 19 14:49:45] [app_getcpeid.so][Dec 19 14:49:45] => (Get ADSI CPE ID) [Dec 19 14:49:45] == Registered application 'GetCPEID' [Dec 19 14:49:45] [app_milliwatt.so][Dec 19 14:49:45] => (Digital Milliwatt (mu-law) Test Application) [Dec 19 14:49:45] == Registered application 'Milliwatt' [Dec 19 14:49:45] [app_zapateller.so][Dec 19 14:49:45] => (Block Telemarketers with Special Information Tone) [Dec 19 14:49:45] == Registered application 'Zapateller' [Dec 19 14:49:45] [app_setcallerid.so][Dec 19 14:49:45] => (Set CallerID Application) [Dec 19 14:49:45] == Registered application 'SetCallerPres' [Dec 19 14:49:45] == Registered application 'SetCallerID' [Dec 19 14:49:45] [app_festival.so][Dec 19 14:49:45] => (Simple Festival Interface) [Dec 19 14:49:45] == Registered application 'Festival' [Dec 19 14:49:45] [app_queue.so][Dec 19 14:49:45] => (True Call Queueing) [Dec 19 14:49:45] == Registered application 'Queue' [Dec 19 14:49:45] == Manager registered action Queues [Dec 19 14:49:45] == Manager registered action QueueStatus [Dec 19 14:49:45] == Manager registered action QueueAdd [Dec 19 14:49:45] == Manager registered action QueueRemove [Dec 19 14:49:45] == Manager registered action QueuePause [Dec 19 14:49:45] == Registered application 'AddQueueMember' [Dec 19 14:49:45] == Registered application 'RemoveQueueMember' [Dec 19 14:49:45] == Registered application 'PauseQueueMember' [Dec 19 14:49:45] == Registered application 'UnpauseQueueMember' [Dec 19 14:49:45] == Registered custom function QUEUEAGENTCOUNT [Dec 19 14:49:45] == Parsing '/etc/asterisk/queues.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/queues.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] [app_senddtmf.so][Dec 19 14:49:45] => (Send DTMF digits Application) [Dec 19 14:49:45] == Registered application 'SendDTMF' [Dec 19 14:49:45] [app_parkandannounce.so][Dec 19 14:49:45] => (Call Parking and Announce Application) [Dec 19 14:49:45] == Registered application 'ParkAndAnnounce' [Dec 19 14:49:45] [app_setcidname.so][Dec 19 14:49:45] => (Set CallerID Name) [Dec 19 14:49:45] == Registered application 'SetCIDName' [Dec 19 14:49:45] [app_lookupcidname.so][Dec 19 14:49:45] => (Look up CallerID Name from local database) [Dec 19 14:49:45] == Registered application 'LookupCIDName' [Dec 19 14:49:45] [app_macro.so][Dec 19 14:49:45] => (Extension Macros) [Dec 19 14:49:45] == Registered application 'MacroExit' [Dec 19 14:49:45] == Registered application 'MacroIf' [Dec 19 14:49:45] == Registered application 'Macro' [Dec 19 14:49:45] [app_authenticate.so][Dec 19 14:49:45] => (Authentication Application) [Dec 19 14:49:45] == Registered application 'Authenticate' [Dec 19 14:49:45] [app_softhangup.so][Dec 19 14:49:45] => (Hangs up the requested channel) [Dec 19 14:49:45] == Registered application 'SoftHangup' [Dec 19 14:49:45] [app_lookupblacklist.so][Dec 19 14:49:45] => (Look up Caller*ID name/number from blacklist database) [Dec 19 14:49:45] == Registered application 'LookupBlacklist' [Dec 19 14:49:45] [app_waitforring.so][Dec 19 14:49:45] => (Waits until first ring after time) [Dec 19 14:49:45] == Registered application 'WaitForRing' [Dec 19 14:49:45] [app_privacy.so][Dec 19 14:49:45] => (Require phone number to be entered, if no CallerID sent) [Dec 19 14:49:45] == Registered application 'PrivacyManager' [Dec 19 14:49:45] [app_db.so][Dec 19 14:49:45] => (Database Access Functions) [Dec 19 14:49:45] == Registered application 'DBget' [Dec 19 14:49:45] == Registered application 'DBput' [Dec 19 14:49:45] == Registered application 'DBdel' [Dec 19 14:49:45] == Registered application 'DBdeltree' [Dec 19 14:49:45] [app_chanisavail.so][Dec 19 14:49:45] => (Check channel availability) [Dec 19 14:49:45] == Registered application 'ChanIsAvail' [Dec 19 14:49:45] [app_enumlookup.so][Dec 19 14:49:45] => (ENUM Lookup) [Dec 19 14:49:45] == Registered application 'EnumLookup' [Dec 19 14:49:45] == Parsing '/etc/asterisk/enum.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/enum.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] [app_transfer.so][Dec 19 14:49:45] => (Transfer) [Dec 19 14:49:45] == Registered application 'Transfer' [Dec 19 14:49:45] [app_setcidnum.so][Dec 19 14:49:45] => (Set CallerID Number) [Dec 19 14:49:45] == Registered application 'SetCIDNum' [Dec 19 14:49:45] [app_cdr.so][Dec 19 14:49:45] => (Tell Asterisk to not maintain a CDR for the current call) [Dec 19 14:49:45] == Registered application 'NoCDR' [Dec 19 14:49:45] [app_hasnewvoicemail.so][Dec 19 14:49:45] => (Indicator for whether a voice mailbox has messages in a given folder.) [Dec 19 14:49:45] == Registered custom function VMCOUNT [Dec 19 14:49:45] == Registered application 'HasVoicemail' [Dec 19 14:49:45] == Registered application 'HasNewVoicemail' [Dec 19 14:49:45] [app_sayunixtime.so][Dec 19 14:49:45] => (Say time) [Dec 19 14:49:45] == Registered application 'SayUnixTime' [Dec 19 14:49:45] == Registered application 'DateTime' [Dec 19 14:49:45] [app_cut.so][Dec 19 14:49:45] => (Cut out information from a string) [Dec 19 14:49:45] == Registered custom function CUT [Dec 19 14:49:45] == Registered custom function SORT [Dec 19 14:49:45] == Registered application 'Sort' [Dec 19 14:49:45] == Registered application 'Cut' [Dec 19 14:49:45] [app_read.so][Dec 19 14:49:45] => (Read Variable Application) [Dec 19 14:49:45] == Registered application 'Read' [Dec 19 14:49:45] [app_setcdruserfield.so][Dec 19 14:49:45] => (CDR user field apps) [Dec 19 14:49:45] == Registered application 'SetCDRUserField' [Dec 19 14:49:45] == Registered application 'AppendCDRUserField' [Dec 19 14:49:45] == Manager registered action SetCDRUserField [Dec 19 14:49:45] [app_random.so][Dec 19 14:49:45] => (Random goto) [Dec 19 14:49:45] == Registered application 'Random' [Dec 19 14:49:45] [app_ices.so][Dec 19 14:49:45] => (Encode and Stream via icecast and ices) [Dec 19 14:49:45] == Registered application 'ICES' [Dec 19 14:49:45] [app_eval.so][Dec 19 14:49:45] => (Reevaluates strings) [Dec 19 14:49:45] == Registered application 'Eval' [Dec 19 14:49:45] [app_nbscat.so][Dec 19 14:49:45] => (Silly NBS Stream Application) [Dec 19 14:49:45] == Registered application 'NBScat' [Dec 19 14:49:45] [app_sendtext.so][Dec 19 14:49:45] => (Send Text Applications) [Dec 19 14:49:45] == Registered application 'SendText' [Dec 19 14:49:45] [app_exec.so][Dec 19 14:49:45] => (Executes applications) [Dec 19 14:49:45] == Registered application 'Exec' [Dec 19 14:49:45] [app_groupcount.so][Dec 19 14:49:45] => (Group Management Routines) [Dec 19 14:49:45] == Registered application 'GetGroupCount' [Dec 19 14:49:45] == Registered application 'SetGroup' [Dec 19 14:49:45] == Registered application 'CheckGroup' [Dec 19 14:49:45] == Registered application 'GetGroupMatchCount' [Dec 19 14:49:45] [app_txtcidname.so][Dec 19 14:49:45] => (TXTCIDName) [Dec 19 14:49:45] == Registered application 'TXTCIDName' [Dec 19 14:49:45] [app_controlplayback.so][Dec 19 14:49:45] => (Control Playback Application) [Dec 19 14:49:45] == Registered application 'ControlPlayback' [Dec 19 14:49:45] [app_talkdetect.so][Dec 19 14:49:45] => (Playback with Talk Detection) [Dec 19 14:49:45] == Registered application 'BackgroundDetect' [Dec 19 14:49:45] [app_alarmreceiver.so][Dec 19 14:49:45] => (Alarm Receiver for Asterisk) [Dec 19 14:49:45] == Parsing '/etc/asterisk/alarmreceiver.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/alarmreceiver.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] == Registered application 'AlarmReceiver' [Dec 19 14:49:45] [app_userevent.so][Dec 19 14:49:45] => (Custom User Event Application) [Dec 19 14:49:45] == Registered application 'UserEvent' [Dec 19 14:49:45] [app_verbose.so][Dec 19 14:49:45] => (Send verbose output) [Dec 19 14:49:45] == Registered application 'Verbose' [Dec 19 14:49:45] [app_test.so][Dec 19 14:49:45] => (Interface Test Application) [Dec 19 14:49:45] == Registered application 'TestClient' [Dec 19 14:49:45] == Registered application 'TestServer' [Dec 19 14:49:45] [app_forkcdr.so][Dec 19 14:49:45] => (Fork The CDR into 2 separate entities.) [Dec 19 14:49:45] == Registered application 'ForkCDR' [Dec 19 14:49:45] [app_math.so][Dec 19 14:49:45] => (Basic Math Functions) [Dec 19 14:49:45] == Registered application 'Math' [Dec 19 14:49:45] [app_realtime.so][Dec 19 14:49:45] => (Realtime Data Lookup/Rewrite) [Dec 19 14:49:45] == Registered application 'RealTimeUpdate' [Dec 19 14:49:45] == Registered application 'RealTime' [Dec 19 14:49:45] [app_dumpchan.so][Dec 19 14:49:45] => (Dump Info About The Calling Channel) [Dec 19 14:49:45] == Registered application 'DumpChan' [Dec 19 14:49:45] [app_waitforsilence.so][Dec 19 14:49:45] => (Wait For Silence) [Dec 19 14:49:45] == Registered application 'WaitForSilence' [Dec 19 14:49:45] [app_while.so][Dec 19 14:49:45] => (While Loops and Conditional Execution) [Dec 19 14:49:45] == Registered application 'While' [Dec 19 14:49:45] == Registered application 'ExecIf' [Dec 19 14:49:45] == Registered application 'EndWhile' [Dec 19 14:49:45] [app_setrdnis.so][Dec 19 14:49:45] => (Set RDNIS Number) [Dec 19 14:49:45] == Registered application 'SetRDNIS' [Dec 19 14:49:45] [app_md5.so][Dec 19 14:49:45] => (MD5 checksum applications) [Dec 19 14:49:45] == Registered application 'MD5Check' [Dec 19 14:49:45] == Registered application 'MD5' [Dec 19 14:49:45] [app_readfile.so][Dec 19 14:49:45] => (Stores output of file into a variable) [Dec 19 14:49:45] == Registered application 'ReadFile' [Dec 19 14:49:45] [app_chanspy.so][Dec 19 14:49:45] => (Listen to the audio of an active channel ) [Dec 19 14:49:45] == Registered application 'ChanSpy' [Dec 19 14:49:45] [app_settransfercapability.so][Dec 19 14:49:45] => (Set ISDN Transfer Capability) [Dec 19 14:49:45] == Registered application 'SetTransferCapability' [Dec 19 14:49:45] [app_dictate.so][Dec 19 14:49:45] => (Virtual Dictation Machine) [Dec 19 14:49:45] == Registered application 'Dictate' [Dec 19 14:49:45] [app_externalivr.so][Dec 19 14:49:45] => (External IVR Interface Application) [Dec 19 14:49:45] == Registered application 'ExternalIVR' [Dec 19 14:49:45] [app_directed_pickup.so][Dec 19 14:49:45] => (Directed Call Pickup Application) [Dec 19 14:49:45] == Registered application 'Pickup' [Dec 19 14:49:45] [app_mixmonitor.so][Dec 19 14:49:45] => (Mixed Audio Monitoring Application) [Dec 19 14:49:45] == Registered application 'MixMonitor' [Dec 19 14:49:45] [app_stack.so][Dec 19 14:49:45] => (Stack Routines) [Dec 19 14:49:45] == Registered application 'StackPop' [Dec 19 14:49:45] == Registered application 'Return' [Dec 19 14:49:45] == Registered application 'GosubIf' [Dec 19 14:49:45] == Registered application 'Gosub' [Dec 19 14:49:45] [app_sms.so][Dec 19 14:49:45] => (SMS/PSTN handler) [Dec 19 14:49:45] == Registered application 'SMS' [Dec 19 14:49:45] [codec_ilbc.so][Dec 19 14:49:45] => (iLBC/PCM16 (signed linear) Codec Translator) [Dec 19 14:49:45] == Registered translator 'ilbctolin' from format ilbc to slin, cost 10 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] == Registered translator 'lintoilbc' from format slin to ilbc, cost 54 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] [codec_gsm.so][Dec 19 14:49:45] => (GSM/PCM16 (signed linear) Codec Translator) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_gsm: using generic PLC [Dec 19 14:49:45] == Registered translator 'gsmtolin' from format gsm to slin, cost 3 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] == Registered translator 'lintogsm' from format slin to gsm, cost 9 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] [codec_lpc10.so][Dec 19 14:49:45] => (LPC10 2.4kbps (signed linear) Voice Coder) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_lpc10: using generic PLC [Dec 19 14:49:45] == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 8 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] == Registered translator 'lintolpc10' from format slin to lpc10, cost 11 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] [codec_adpcm.so][Dec 19 14:49:45] => (Adaptive Differential PCM Coder/Decoder) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_adpcm: using generic PLC [Dec 19 14:49:45] == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] [codec_ulaw.so][Dec 19 14:49:45] => (Mu-law Coder/Decoder) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_ulaw: using generic PLC [Dec 19 14:49:45] == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] [codec_alaw.so][Dec 19 14:49:45] => (A-law Coder/Decoder) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_alaw: using generic PLC [Dec 19 14:49:45] == Registered translator 'alawtolin' from format alaw to slin, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] == Registered translator 'lintoalaw' from format slin to alaw, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] [codec_a_mu.so][Dec 19 14:49:45] => (A-law and Mulaw direct Coder/Decoder) [Dec 19 14:49:45] == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 56 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 [Dec 19 14:49:45] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 56 cost path from unknown to unknown, via 2 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:45] [codec_g726.so][Dec 19 14:49:45] => (ITU G.726-32kbps G726 Transcoder) [Dec 19 14:49:45] == Parsing '/etc/asterisk/codecs.conf': [Dec 19 14:49:45] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Dec 19 14:49:45] Found [Dec 19 14:49:45] -- codec_g726: using generic PLC [Dec 19 14:49:46] == Registered translator 'g726tolin' from format g726 to slin, cost 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 56 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 15 cost path from ulaw to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 60 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] == Registered translator 'lintog726' from format slin to g726, cost 7 [Dec 19 14:49:46] DEBUG[9880]: translate.c:278 rebuild_matrix: Resetting translation matrix [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from g723 to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 14 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 57 cost path from g723 to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from gsm to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 8 cost path from gsm to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from gsm to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to g723, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to ulaw, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 13 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 56 cost path from unknown to unknown, via 2 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 8 cost path from unknown to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 15 cost path from ulaw to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 7 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 60 cost path from ulaw to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 10 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 8 cost path from unknown to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 55 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 15 cost path from unknown to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 9 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 62 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to g723, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to gsm, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 17 cost path from unknown to ulaw, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] DEBUG[9880]: translate.c:322 rebuild_matrix: Discovered 21 cost path from unknown to unknown, via 6 [Dec 19 14:49:46] [format_gsm.so][Dec 19 14:49:46] => (Raw GSM data) [Dec 19 14:49:46] == Registered file format gsm, extension(s) gsm [Dec 19 14:49:46] [format_wav.so][Dec 19 14:49:46] => (Microsoft WAV format (8000hz Signed Linear)) [Dec 19 14:49:46] == Registered file format wav, extension(s) wav [Dec 19 14:49:46] [format_wav_gsm.so][Dec 19 14:49:46] => (Microsoft WAV format (Proprietary GSM)) [Dec 19 14:49:46] == Registered file format wav49, extension(s) WAV|wav49 [Dec 19 14:49:46] [format_vox.so][Dec 19 14:49:46] => (Dialogic VOX (ADPCM) File Format) [Dec 19 14:49:46] == Registered file format vox, extension(s) vox [Dec 19 14:49:46] [format_pcm.so][Dec 19 14:49:46] => (Raw uLaw 8khz Audio support (PCM)) [Dec 19 14:49:46] == Registered file format pcm, extension(s) pcm|ulaw|ul|mu [Dec 19 14:49:46] [format_g729.so][Dec 19 14:49:46] => (Raw G729 data) [Dec 19 14:49:46] == Registered file format g729, extension(s) g729 [Dec 19 14:49:46] [format_pcm_alaw.so][Dec 19 14:49:46] => (Raw aLaw 8khz PCM Audio support) [Dec 19 14:49:46] == Registered file format alaw, extension(s) alaw|al [Dec 19 14:49:46] [format_h263.so][Dec 19 14:49:46] => (Raw h263 data) [Dec 19 14:49:46] == Registered file format h263, extension(s) h263 [Dec 19 14:49:46] [format_g726.so][Dec 19 14:49:46] => (Raw G.726 (16/24/32/40kbps) data) [Dec 19 14:49:46] == Registered file format g726-40, extension(s) g726-40 [Dec 19 14:49:46] == Registered file format g726-32, extension(s) g726-32 [Dec 19 14:49:46] == Registered file format g726-24, extension(s) g726-24 [Dec 19 14:49:46] == Registered file format g726-16, extension(s) g726-16 [Dec 19 14:49:46] [format_ilbc.so][Dec 19 14:49:46] => (Raw iLBC data) [Dec 19 14:49:46] == Registered file format iLBC, extension(s) ilbc [Dec 19 14:49:46] [format_sln.so][Dec 19 14:49:46] => (Raw Signed Linear Audio support (SLN)) [Dec 19 14:49:46] == Registered file format sln, extension(s) sln|raw [Dec 19 14:49:46] [format_au.so][Dec 19 14:49:46] => (Sun Microsystems AU format (signed linear)) [Dec 19 14:49:46] == Registered file format au, extension(s) au [Dec 19 14:49:46] [format_jpeg.so][Dec 19 14:49:46] => (JPEG (Joint Picture Experts Group) Image Format) [Dec 19 14:49:46] == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) [Dec 19 14:49:46] [format_g723.so][Dec 19 14:49:46] => (G.723.1 Simple Timestamp File Format) [Dec 19 14:49:46] == Registered file format g723sf, extension(s) g723|g723sf [Dec 19 14:49:46] [cdr_csv.so][Dec 19 14:49:46] => (Comma Separated Values CDR Backend) [Dec 19 14:49:46] [cdr_manager.so][Dec 19 14:49:46] => (Asterisk Call Manager CDR Backend) [Dec 19 14:49:46] == Parsing '/etc/asterisk/cdr_manager.conf': [Dec 19 14:49:46] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr_manager.conf [Dec 19 14:49:46] Found [Dec 19 14:49:46] [cdr_custom.so][Dec 19 14:49:46] => (Customizable Comma Separated Values CDR Backend) [Dec 19 14:49:46] == Parsing '/etc/asterisk/cdr_custom.conf': [Dec 19 14:49:46] DEBUG[9880]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr_custom.conf [Dec 19 14:49:46] Found [Dec 19 14:49:46] [func_callerid.so][Dec 19 14:49:46] => (Caller ID related dialplan function) [Dec 19 14:49:46] == Registered custom function CALLERID [Dec 19 14:49:46] [func_enum.so][Dec 19 14:49:46] => (ENUM Related Functions) [Dec 19 14:49:46] == Registered custom function ENUMLOOKUP [Dec 19 14:49:46] == Registered custom function TXTCIDNAME [Dec 19 14:49:46] [func_uri.so][Dec 19 14:49:46] => (URI encode/decode functions) [Dec 19 14:49:46] == Registered custom function URIDECODE [Dec 19 14:49:46] == Registered custom function URIENCODE [Dec 19 14:49:46] Asterisk Ready. ]1;Asterisk]2;Asterisk Console on 'asterisktest.sip.local' (pid 9880)*CLI> sip debuget verbose 4debug 4 Core debug was 5 and is now 4 *CLI> set debug 4ip debuget verbose 4 Verbosity was 5 and is now 4 *CLI> set verbose 4debug 4ip debug SIP Debugging enabled *CLI> [Dec 19 14:50:16] <-- SIP read from 192.168.100.100:5061: INVITE sip:024423232@192.168.30.20;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 From: ;tag=7c189ddf To: CSeq: 1 INVITE Contact: Supported: 100rel Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REGISTER,PRACK,INFO,UPDATE,SUBSCRIBE,NOTIFY,MESSAGE,REFER Content-Length: 334 Content-Type: application/sdp v=0 o=HuaweiSoftX3000 12 12 IN IP4 192.168.100.100 s=Sip Call c=IN IP4 192.168.100.101 t=0 0 m=audio 2508 RTP/AVP 8 0 18 4 97 96 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:4 G723/8000 a=rtpmap:97 telephone-event/8000 a=rtpmap:96 red/8000 a=fmtp:97 0-15 a=fmtp:96 97/97 a=fmtp:18 annexb=yes [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: INVITE sip:024423232@192.168.30.20;user=phone SIP/2.0 (57) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb (60) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 (56) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: ;tag=7c189ddf (62) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: (48) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 1 INVITE (14) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Contact: (57) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: Supported: 100rel (17) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 8: Max-Forwards: 70 (16) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 9: Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REGISTER,PRACK,INFO,UPDATE,SUBSCRIBE,NOTIFY,MESSAGE,REFER (94) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 10: Content-Length: 334 (19) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 11: Content-Type: application/sdp (29) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 12: (0) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: v=0 (3) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: o=HuaweiSoftX3000 12 12 IN IP4 192.168.100.100 (45) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: s=Sip Call (10) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: c=IN IP4 192.168.100.101 (24) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: t=0 0 (5) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: m=audio 2508 RTP/AVP 8 0 18 4 97 96 (35) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:18 G729/8000 (21) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:4 G723/8000 (20) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:97 telephone-event/8000 (32) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=rtpmap:96 red/8000 (20) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=fmtp:97 0-15 (14) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=fmtp:96 97/97 (15) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3460 parse_request: Line: a=fmtp:18 annexb=yes (20) [Dec 19 14:50:16] --- (12 headers 15 lines) --- [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3177 sip_alloc: Allocating new SIP dialog for b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 - INVITE (With RTP) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:11273 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1014 parse_sip_options: Begin: parsing SIP "Supported: 100rel" [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1026 parse_sip_options: Found SIP option: -100rel- [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1032 parse_sip_options: Matched SIP option: 100rel [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1043 parse_sip_options: * SIP extension value: 2 for call b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 [Dec 19 14:50:16] Using INVITE request as basis request - b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 [Dec 19 14:50:16] Sending to 192.168.100.100 : 5061 (non-NAT) [Dec 19 14:50:16] Found peer 'sip' [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:7354 check_user_full: Setting NAT on RTP to 0 [Dec 19 14:50:16] Found RTP audio format 8 [Dec 19 14:50:16] Found RTP audio format 0 [Dec 19 14:50:16] Found RTP audio format 18 [Dec 19 14:50:16] Found RTP audio format 4 [Dec 19 14:50:16] Found RTP audio format 97 [Dec 19 14:50:16] Found RTP audio format 96 [Dec 19 14:50:16] Peer audio RTP is at port 192.168.100.101:2508 [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3669 process_sdp: Peer audio RTP is at port 192.168.100.101:2508 [Dec 19 14:50:16] Found description format PCMA [Dec 19 14:50:16] Found description format PCMU [Dec 19 14:50:16] Found description format G729 [Dec 19 14:50:16] Found description format G723 [Dec 19 14:50:16] Found description format telephone-event [Dec 19 14:50:16] Found description format red [Dec 19 14:50:16] Capabilities: us - 0x8 (alaw), peer - audio=0x10d (g723|ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x8 (alaw) [Dec 19 14:50:16] Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:10637 handle_request_invite: Checking SIP call limits for device [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:2220 update_call_counter: Updating call counter for incoming call [Dec 19 14:50:16] Looking for 024423232 in sip-incoming (domain 192.168.30.20;user=phone) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:6242 build_route: build_route: Contact hop: [Dec 19 14:50:16] list_route: hop: [Dec 19 14:50:16] Transmitting (no NAT) to 192.168.100.100:5061: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb;received=192.168.100.100 From: ;tag=7c189ddf To: Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 CSeq: 1 INVITE User-Agent: Asterisk Test 01 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- [Dec 19 14:50:16] DEBUG[9898]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' [Dec 19 14:50:16] DEBUG[9884]: chan_sip.c:11815 sip_devicestate: Checking device state for peer 192.168.100.100 [Dec 19 14:50:16] DEBUG[9884]: devicestate.c:187 do_state_change: Changing state for SIP/192.168.100.100 - state 2 (In use) [Dec 19 14:50:16] DEBUG[9899]: app_queue.c:500 changethread: Device 'SIP/192.168.100.100' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 19 14:50:16] -- Executing Dial("SIP/192.168.100.100-08194e10", "SIP/032222222@sip|60") in new stack [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3177 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:1888 create_addr_from_peer: Setting NAT on RTP to 0 [Dec 19 14:50:16] DEBUG[9898]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-sip-incoming-024423232-1. [Dec 19 14:50:16] DEBUG[9898]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Dec 19 14:50:16] DEBUG[9898]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Dec 19 14:50:16] DEBUG[9898]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPURI. [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:2082 sip_call: Outgoing Call for 032222222 [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:2220 update_call_counter: Updating call counter for outgoing call [Dec 19 14:50:16] We're at 192.168.30.20 port 35468 [Dec 19 14:50:16] Adding codec 0x8 (alaw) to SDP [Dec 19 14:50:16] Adding non-codec 0x1 (telephone-event) to SDP [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 0: INVITE sip:032222222@192.168.100.100 SIP/2.0 (45) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport (65) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 2: From: "01012345" ;tag=as46d5891a (67) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 3: To: (36) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 4: Contact: (41) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 5: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 (56) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 6: CSeq: 102 INVITE (16) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 7: User-Agent: Asterisk Test 01 (33) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 8: Max-Forwards: 70 (16) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 9: Date: Tue, 19 Dec 2006 13:50:16 GMT (35) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 11: Content-Type: application/sdp (29) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 12: Content-Length: 218 (19) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3428 parse_request: Header 13: (0) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: v=0 (3) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: o=root 9880 9880 IN IP4 192.168.30.20 (38) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: s=session (9) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: c=IN IP4 192.168.30.20 (23) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: t=0 0 (5) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: m=audio 35468 RTP/AVP 8 101 (27) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: a=fmtp:101 0-16 (15) [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:3460 parse_request: Line: a=silenceSupp:off - - - - (25) [Dec 19 14:50:16] 13 headers, 10 lines [Dec 19 14:50:16] Reliably Transmitting (no NAT) to 192.168.100.100:5060: INVITE sip:032222222@192.168.100.100 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport From: "01012345" ;tag=as46d5891a To: Contact: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 CSeq: 102 INVITE User-Agent: Asterisk Test 01 Max-Forwards: 70 Date: Tue, 19 Dec 2006 13:50:16 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 218 v=0 o=root 9880 9880 IN IP4 192.168.30.20 s=session c=IN IP4 192.168.30.20 t=0 0 m=audio 35468 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- [Dec 19 14:50:16] DEBUG[9898]: chan_sip.c:1306 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1 [Dec 19 14:50:16] -- Called 032222222@sip [Dec 19 14:50:16] <-- SIP read from 192.168.100.100:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 From: "01012345" ;tag=as46d5891a To: CSeq: 102 INVITE Content-Length: 0 [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: SIP/2.0 100 Trying (18) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 (70) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 (56) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: "01012345" ;tag=as46d5891a (67) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: (36) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Content-Length: 0 (17) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: (0) [Dec 19 14:50:16] --- (7 headers 0 lines) --- [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1458 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #1 - INVITE (got response) [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:1467 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20' Request 102: Found [Dec 19 14:50:16] DEBUG[9889]: chan_sip.c:9695 handle_response_invite: SIP response 100 to standard invite [Dec 19 14:50:17] <-- SIP read from 192.168.100.100:5061: OPTIONS sip:192.168.30.20:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK7a7f2fa80 Call-ID: 17649ea8e022d8a541898f8714689abe@192.168.100.100 From: ;tag=158df0ad To: CSeq: 1 OPTIONS Max-Forwards: 70 Content-Length: 0 [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: OPTIONS sip:192.168.30.20:5060 SIP/2.0 (39) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK7a7f2fa80 (60) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: 17649ea8e022d8a541898f8714689abe@192.168.100.100 (56) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: ;tag=158df0ad (44) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: (24) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 1 OPTIONS (15) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: Content-Length: 0 (17) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 8: (0) [Dec 19 14:50:17] --- (8 headers 0 lines) --- [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:3177 sip_alloc: Allocating new SIP dialog for 17649ea8e022d8a541898f8714689abe@192.168.100.100 - OPTIONS (No RTP) [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:11273 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS [Dec 19 14:50:17] Looking for s in sip-incoming (domain 192.168.30.20) [Dec 19 14:50:17] Transmitting (no NAT) to 192.168.100.100:5061: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK7a7f2fa80;received=192.168.100.100 From: ;tag=158df0ad To: ;tag=as10bb2297 Call-ID: 17649ea8e022d8a541898f8714689abe@192.168.100.100 CSeq: 1 OPTIONS User-Agent: Asterisk Test 01 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Accept: application/sdp Content-Length: 0 --- [Dec 19 14:50:17] DEBUG[9889]: chan_sip.c:11471 sipsock_read: SIP message could not be handled, bad request: 17649ea8e022d8a541898f8714689abe@192.168.100.100 [Dec 19 14:50:17] Destroying call '17649ea8e022d8a541898f8714689abe@192.168.100.100' [Dec 19 14:50:18] <-- SIP read from 192.168.100.100:5061: CANCEL sip:024423232@192.168.30.20;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 From: ;tag=7c189ddf To: CSeq: 1 CANCEL Reason: Q.850;cause=16;text="normal call clearing" Max-Forwards: 70 Content-Length: 0 [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: CANCEL sip:024423232@192.168.30.20;user=phone SIP/2.0 (57) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb (60) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 (56) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: ;tag=7c189ddf (62) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: (48) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 1 CANCEL (14) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Reason: Q.850;cause=16;text="normal call clearing" (50) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: Max-Forwards: 70 (16) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 8: Content-Length: 0 (17) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 9: (0) [Dec 19 14:50:18] --- (9 headers 0 lines) --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:11273 handle_request: **** Received CANCEL (14) - Command in SIP CANCEL [Dec 19 14:50:18] Sending to 192.168.100.100 : 5061 (non-NAT) [Dec 19 14:50:18] Reliably Transmitting (no NAT) to 192.168.100.100:5061: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb;received=192.168.100.100 From: ;tag=7c189ddf To: ;tag=as3d973ab8 Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 CSeq: 1 INVITE User-Agent: Asterisk Test 01 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1306 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #2 [Dec 19 14:50:18] Transmitting (no NAT) to 192.168.100.100:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb;received=192.168.100.100 From: ;tag=7c189ddf To: ;tag=as3d973ab8 Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 CSeq: 1 CANCEL User-Agent: Asterisk Test 01 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- [Dec 19 14:50:18] DEBUG[9898]: channel.c:1367 ast_hangup: Hanging up channel 'SIP/sip-0819a350' [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2431 sip_hangup: Hangup call SIP/sip-0819a350, SIP callid 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20) [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2439 sip_hangup: update_call_counter(032222222) - decrement call limit counter [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2220 update_call_counter: Updating call counter for outgoing call [Dec 19 14:50:18] Scheduling destruction of call '5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20' in 32000 ms [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:1391 __sip_ack: Acked pending invite 102 [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:1414 __sip_ack: Stopping retransmission on '5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20' of Request 102: Match Found [Dec 19 14:50:18] Reliably Transmitting (no NAT) to 192.168.100.100:5060: CANCEL sip:032222222@192.168.100.100 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport From: "01012345" ;tag=as46d5891a To: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 CSeq: 102 CANCEL User-Agent: Asterisk Test 01 Max-Forwards: 70 Content-Length: 0 --- [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:1306 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #4 [Dec 19 14:50:18] DEBUG[9898]: app_dial.c:1636 dial_exec_full: Exiting with DIALSTATUS=CANCEL. [Dec 19 14:50:18] DEBUG[9898]: pbx.c:2316 __ast_pbx_run: Spawn extension (sip-incoming,024423232,1) exited non-zero on 'SIP/192.168.100.100-08194e10' [Dec 19 14:50:18] == Spawn extension (sip-incoming, 024423232, 1) exited non-zero on 'SIP/192.168.100.100-08194e10' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '01012345' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '01012345' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '024423232' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'sip-incoming' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/192.168.100.100-08194e10' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/sip-0819a350' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'Dial' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/032222222@sip|60' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-12-19 14:50:16' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-12-19 14:50:18' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '1166536216.0' [Dec 19 14:50:18] <-- SIP read from 192.168.100.100:5061: ACK sip:024423232@192.168.30.20;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb;received=192.168.100.100 Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 From: ;tag=7c189ddf To: ;tag=as3d973ab8 CSeq: 1 ACK Max-Forwards: 70 Content-Length: 0 [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Dec 19 14:50:18] DEBUG[9898]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Dec 19 14:50:18] DEBUG[9898]: channel.c:1367 ast_hangup: Hanging up channel 'SIP/192.168.100.100-08194e10' [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2431 sip_hangup: Hangup call SIP/192.168.100.100-08194e10, SIP callid b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100) [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2439 sip_hangup: update_call_counter() - decrement call limit counter [Dec 19 14:50:18] DEBUG[9898]: chan_sip.c:2220 update_call_counter: Updating call counter for incoming call [Dec 19 14:50:18] DEBUG[9884]: chan_sip.c:11815 sip_devicestate: Checking device state for peer sip [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: ACK sip:024423232@192.168.30.20;user=phone SIP/2.0 (54) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.100.100:5061;branch=z9hG4bK1bd92f0bb;received=192.168.100.100 (84) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100 (56) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: ;tag=7c189ddf (62) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: ;tag=as3d973ab8 (63) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 1 ACK (11) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: Content-Length: 0 (17) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 8: (0) [Dec 19 14:50:18] --- (8 headers 0 lines) --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:11273 handle_request: **** Received ACK (6) - Command in SIP ACK [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1402 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2 [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1414 __sip_ack: Stopping retransmission on 'b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100' of Response 1: Match Found [Dec 19 14:50:18] Destroying call 'b20f44a680fcb8c4a1d0fb75b2e3a2b6@192.168.100.100' [Dec 19 14:50:18] DEBUG[9884]: devicestate.c:187 do_state_change: Changing state for SIP/sip - state 1 (Not in use) [Dec 19 14:50:18] DEBUG[9884]: chan_sip.c:11815 sip_devicestate: Checking device state for peer 192.168.100.100 [Dec 19 14:50:18] <-- SIP read from 192.168.100.100:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 From: "01012345" ;tag=as46d5891a To: CSeq: 102 CANCEL Content-Length: 0 [Dec 19 14:50:18] DEBUG[9884]: devicestate.c:187 do_state_change: Changing state for SIP/192.168.100.100 - state 1 (Not in use) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: SIP/2.0 200 OK (14) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 (70) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 (56) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: "01012345" ;tag=as46d5891a (67) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: (36) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 102 CANCEL (16) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Content-Length: 0 (17) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: (0) [Dec 19 14:50:18] --- (7 headers 0 lines) --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1402 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #4 [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1414 __sip_ack: Stopping retransmission on '5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20' of Request 102: Match Found [Dec 19 14:50:18] <-- SIP read from 192.168.100.100:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 From: "01012345" ;tag=as46d5891a To: ;tag=f75770c7 CSeq: 102 INVITE Content-Length: 0 [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 0: SIP/2.0 487 Request Terminated (30) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport=5060 (70) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 2: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 (56) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 3: From: "01012345" ;tag=as46d5891a (67) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 4: To: ;tag=f75770c7 (49) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 6: Content-Length: 0 (17) [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:3428 parse_request: Header 7: (0) [Dec 19 14:50:18] --- (7 headers 0 lines) --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:1414 __sip_ack: Stopping retransmission on '5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20' of Request 102: Match Not Found [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:9695 handle_response_invite: SIP response 487 to standard invite [Dec 19 14:50:18] Transmitting (no NAT) to 192.168.100.100:5060: ACK sip:032222222@192.168.100.100 SIP/2.0 Via: SIP/2.0/UDP 192.168.30.20:5060;branch=z9hG4bK42dcf12f;rport From: "01012345" ;tag=as46d5891a To: ;tag=f75770c7 Contact: Call-ID: 5857d1c75c5abd8a346fac734e0c67fd@192.168.30.20 CSeq: 102 ACK User-Agent: Asterisk Test 01 Max-Forwards: 70 Content-Length: 0 --- [Dec 19 14:50:18] DEBUG[9889]: chan_sip.c:2220 update_call_counter: Updating call counter for outgoing call [Dec 19 14:50:18] DEBUG[9901]: app_queue.c:500 changethread: Device 'SIP/192.168.100.100' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 19 14:50:18] DEBUG[9900]: app_queue.c:500 changethread: Device 'SIP/sip' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. *CLI> *CLI> stop now [Dec 19 14:50:39] Beginning asterisk shutdown.... [Dec 19 14:50:39] Executing last minute cleanups [Dec 19 14:50:39] Asterisk cleanly ending (0). [Dec 19 14:50:39] DEBUG[9880]: asterisk.c:900 quit_handler: Asterisk ending (0).