Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf [Nov 30 20:51:46] == Parsing '/etc/asterisk/asterisk.conf': [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Parsing '/etc/asterisk/extconfig.conf': [Nov 30 20:51:46] Found [Nov 30 20:51:46] Asterisk 1.2.13, Copyright (C) 1999 - 2006 Digium, Inc. and others. [Nov 30 20:51:46] Created by Mark Spencer [Nov 30 20:51:46] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. [Nov 30 20:51:46] This is free software, with components licensed under the GNU General Public [Nov 30 20:51:46] License version 2 and other licenses; you are welcome to redistribute it under [Nov 30 20:51:46] certain conditions. Type 'show license' for details. [Nov 30 20:51:46] ========================================================================= [Nov 30 20:51:46] == Parsing '/etc/asterisk/logger.conf': Parsing /etc/asterisk/logger.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] Asterisk Event Logger Started /var/log/asterisk/event_log [Nov 30 20:51:46] == Parsing '/etc/asterisk/dnsmgr.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/dnsmgr.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] Asterisk Dynamic Loader loading preload modules: [Nov 30 20:51:46] == Parsing '/etc/asterisk/modules.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/modules.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Manager registered action Ping [Nov 30 20:51:46] == Manager registered action Events [Nov 30 20:51:46] == Manager registered action Logoff [Nov 30 20:51:46] == Manager registered action Hangup [Nov 30 20:51:46] == Manager registered action Status [Nov 30 20:51:46] == Manager registered action Setvar [Nov 30 20:51:46] == Manager registered action Getvar [Nov 30 20:51:46] == Manager registered action Redirect [Nov 30 20:51:46] == Manager registered action Originate [Nov 30 20:51:46] == Manager registered action Command [Nov 30 20:51:46] == Manager registered action ExtensionState [Nov 30 20:51:46] == Manager registered action AbsoluteTimeout [Nov 30 20:51:46] == Manager registered action MailboxStatus [Nov 30 20:51:46] == Manager registered action MailboxCount [Nov 30 20:51:46] == Manager registered action ListCommands [Nov 30 20:51:46] == Parsing '/etc/asterisk/manager.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/manager.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] Asterisk Management interface listening on port 5038 [Nov 30 20:51:46] == Parsing '/etc/asterisk/cdr.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/cdr.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] NOTICE[2215]: cdr.c:1192 do_reload: CDR simple logging enabled. [Nov 30 20:51:46] == Parsing '/etc/asterisk/rtp.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/rtp.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == RTP Allocating from port range 10000 -> 20000 [Nov 30 20:51:46] Asterisk PBX Core Initializing [Nov 30 20:51:46] Registering builtin applications: [Nov 30 20:51:46] [AbsoluteTimeout] [Nov 30 20:51:46] == Registered application 'AbsoluteTimeout' [Nov 30 20:51:46] [Answer] [Nov 30 20:51:46] == Registered application 'Answer' [Nov 30 20:51:46] [BackGround] [Nov 30 20:51:46] == Registered application 'BackGround' [Nov 30 20:51:46] [Busy] [Nov 30 20:51:46] == Registered application 'Busy' [Nov 30 20:51:46] [Congestion] [Nov 30 20:51:46] == Registered application 'Congestion' [Nov 30 20:51:46] [DigitTimeout] [Nov 30 20:51:46] == Registered application 'DigitTimeout' [Nov 30 20:51:46] [Goto] [Nov 30 20:51:46] == Registered application 'Goto' [Nov 30 20:51:46] [GotoIf] [Nov 30 20:51:46] == Registered application 'GotoIf' [Nov 30 20:51:46] [GotoIfTime] [Nov 30 20:51:46] == Registered application 'GotoIfTime' [Nov 30 20:51:46] [ExecIfTime] [Nov 30 20:51:46] == Registered application 'ExecIfTime' [Nov 30 20:51:46] [Hangup] [Nov 30 20:51:46] == Registered application 'Hangup' [Nov 30 20:51:46] [NoOp] [Nov 30 20:51:46] == Registered application 'NoOp' [Nov 30 20:51:46] [Progress] [Nov 30 20:51:46] == Registered application 'Progress' [Nov 30 20:51:46] [ResetCDR] [Nov 30 20:51:46] == Registered application 'ResetCDR' [Nov 30 20:51:46] [ResponseTimeout] [Nov 30 20:51:46] == Registered application 'ResponseTimeout' [Nov 30 20:51:46] [Ringing] [Nov 30 20:51:46] == Registered application 'Ringing' [Nov 30 20:51:46] [SayNumber] [Nov 30 20:51:46] == Registered application 'SayNumber' [Nov 30 20:51:46] [SayDigits] [Nov 30 20:51:46] == Registered application 'SayDigits' [Nov 30 20:51:46] [SayAlpha] [Nov 30 20:51:46] == Registered application 'SayAlpha' [Nov 30 20:51:46] [SayPhonetic] [Nov 30 20:51:46] == Registered application 'SayPhonetic' [Nov 30 20:51:46] [SetAccount] [Nov 30 20:51:46] == Registered application 'SetAccount' [Nov 30 20:51:46] [SetAMAFlags] [Nov 30 20:51:46] == Registered application 'SetAMAFlags' [Nov 30 20:51:46] [SetGlobalVar] [Nov 30 20:51:46] == Registered application 'SetGlobalVar' [Nov 30 20:51:46] [SetLanguage] [Nov 30 20:51:46] == Registered application 'SetLanguage' [Nov 30 20:51:46] [Set] [Nov 30 20:51:46] == Registered application 'Set' [Nov 30 20:51:46] [SetVar] [Nov 30 20:51:46] == Registered application 'SetVar' [Nov 30 20:51:46] [ImportVar] [Nov 30 20:51:46] == Registered application 'ImportVar' [Nov 30 20:51:46] [Wait] [Nov 30 20:51:46] == Registered application 'Wait' [Nov 30 20:51:46] [WaitExten] [Nov 30 20:51:46] == Registered application 'WaitExten' [Nov 30 20:51:46] == Manager registered action DBGet [Nov 30 20:51:46] == Manager registered action DBPut [Nov 30 20:51:46] == Parsing '/etc/asterisk/enum.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/enum.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] Asterisk Dynamic Loader Starting: [Nov 30 20:51:46] == Parsing '/etc/asterisk/modules.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/modules.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [res_musiconhold.so][Nov 30 20:51:46] => (Music On Hold Resource) [Nov 30 20:51:46] == Registered application 'MusicOnHold' [Nov 30 20:51:46] == Registered application 'WaitMusicOnHold' [Nov 30 20:51:46] == Registered application 'SetMusicOnHold' [Nov 30 20:51:46] == Registered application 'StartMusicOnHold' [Nov 30 20:51:46] == Registered application 'StopMusicOnHold' [Nov 30 20:51:46] == Parsing '/etc/asterisk/musiconhold.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/musiconhold.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] WARNING[2221]: res_musiconhold.c:336 spawn_mp3: /usr/share/asterisk/mohmp3 is not a valid directory [Nov 30 20:51:46] WARNING[2221]: res_musiconhold.c:493 monmp3thread: Unable to spawn mp3player [Nov 30 20:51:46] [res_indications.so][Nov 30 20:51:46] => (Indications Configuration) [Nov 30 20:51:46] == Parsing '/etc/asterisk/indications.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/indications.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- Registered indication country 'at' [Nov 30 20:51:46] -- Registered indication country 'au' [Nov 30 20:51:46] -- Registered indication country 'br' [Nov 30 20:51:46] -- Registered indication country 'be' [Nov 30 20:51:46] -- Registered indication country 'ch' [Nov 30 20:51:46] -- Registered indication country 'cl' [Nov 30 20:51:46] -- Registered indication country 'cn' [Nov 30 20:51:46] -- Registered indication country 'cz' [Nov 30 20:51:46] -- Registered indication country 'de' [Nov 30 20:51:46] -- Registered indication country 'dk' [Nov 30 20:51:46] -- Registered indication country 'ee' [Nov 30 20:51:46] -- Registered indication country 'es' [Nov 30 20:51:46] -- Registered indication country 'fi' [Nov 30 20:51:46] -- Registered indication country 'fr' [Nov 30 20:51:46] -- Registered indication country 'gr' [Nov 30 20:51:46] -- Registered indication country 'hu' [Nov 30 20:51:46] -- Registered indication country 'it' [Nov 30 20:51:46] -- Registered indication country 'lt' [Nov 30 20:51:46] -- Registered indication country 'mx' [Nov 30 20:51:46] -- Registered indication country 'nl' [Nov 30 20:51:46] -- Registered indication country 'no' [Nov 30 20:51:46] -- Registered indication country 'nz' [Nov 30 20:51:46] -- Registered indication country 'pl' [Nov 30 20:51:46] -- Registered indication country 'pt' [Nov 30 20:51:46] -- Registered indication country 'ru' [Nov 30 20:51:46] -- Registered indication country 'se' [Nov 30 20:51:46] -- Registered indication country 'sg' [Nov 30 20:51:46] -- Registered indication country 'uk' [Nov 30 20:51:46] -- Registered indication country 'us' [Nov 30 20:51:46] -- Registered indication country 'us-o' [Nov 30 20:51:46] -- Registered indication country 'tw' [Nov 30 20:51:46] -- Registered indication country 'za' [Nov 30 20:51:46] -- Setting default indication country to 'us' [Nov 30 20:51:46] == Registered application 'PlayTones' [Nov 30 20:51:46] == Registered application 'StopPlayTones' [Nov 30 20:51:46] [res_monitor.so][Nov 30 20:51:46] => (Call Monitoring Resource) [Nov 30 20:51:46] == Registered application 'Monitor' [Nov 30 20:51:46] == Registered application 'StopMonitor' [Nov 30 20:51:46] == Registered application 'ChangeMonitor' [Nov 30 20:51:46] == Manager registered action Monitor [Nov 30 20:51:46] == Manager registered action StopMonitor [Nov 30 20:51:46] == Manager registered action ChangeMonitor [Nov 30 20:51:46] [res_adsi.so][Nov 30 20:51:46] => (ADSI Resource) [Nov 30 20:51:46] == Parsing '/etc/asterisk/adsi.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/adsi.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [res_agi.so][Nov 30 20:51:46] => (Asterisk Gateway Interface (AGI)) [Nov 30 20:51:46] == Registered application 'DeadAGI' [Nov 30 20:51:46] == Registered application 'EAGI' [Nov 30 20:51:46] == Registered application 'AGI' [Nov 30 20:51:46] [res_features.so][Nov 30 20:51:46] => (Call Features Resource) [Nov 30 20:51:46] == Parsing '/etc/asterisk/features.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/features.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'parkedcalls' [Nov 30 20:51:46] -- Registered extension context 'parkedcalls' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '700' priority 1 to parkedcalls [Nov 30 20:51:46] -- Added extension '700' priority 1 to parkedcalls [Nov 30 20:51:46] == Registered application 'ParkedCall' [Nov 30 20:51:46] == Registered application 'Park' [Nov 30 20:51:46] == Manager registered action ParkedCalls [Nov 30 20:51:46] [res_crypto.so][Nov 30 20:51:46] => (Cryptographic Digital Signatures) [Nov 30 20:51:46] -- Loaded PUBLIC key 'iaxtel' [Nov 30 20:51:46] DEBUG[2215]: res_crypto.c:257 try_load_key: Key 'iaxtel' loaded OK [Nov 30 20:51:46] -- Loaded PUBLIC key 'freeworlddialup' [Nov 30 20:51:46] DEBUG[2215]: res_crypto.c:257 try_load_key: Key 'freeworlddialup' loaded OK [Nov 30 20:51:46] [pbx_config.so][Nov 30 20:51:46] => (Text Extension Configuration) [Nov 30 20:51:46] == Parsing '/etc/asterisk/extensions.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/extensions.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Setting global variable 'CONSOLE' to 'Console/dsp' [Nov 30 20:51:46] == Setting global variable 'IAXINFO' to 'guest' [Nov 30 20:51:46] == Setting global variable 'TRUNK' to 'Zap/g2' [Nov 30 20:51:46] == Setting global variable 'TRUNKMSD' to '1' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-canonical' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-canonical' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-customers' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-customers' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-via-pstn' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-via-pstn' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-local' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-local' [Nov 30 20:51:46] -- Including context 'dundi-e164-canonical' in context 'dundi-e164-local' [Nov 30 20:51:46] -- Including context 'dundi-e164-customers' in context 'dundi-e164-local' [Nov 30 20:51:46] -- Including context 'dundi-e164-via-pstn' in context 'dundi-e164-local' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-switch' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-switch' [Nov 30 20:51:46] -- Including switch 'DUNDi/e164' in context 'dundi-e164-switch' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'dundi-e164-lookup' [Nov 30 20:51:46] -- Registered extension context 'dundi-e164-lookup' [Nov 30 20:51:46] -- Including context 'dundi-e164-local' in context 'dundi-e164-lookup' [Nov 30 20:51:46] -- Including context 'dundi-e164-switch' in context 'dundi-e164-lookup' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'macro-dundi-e164' [Nov 30 20:51:46] -- Registered extension context 'macro-dundi-e164' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-dundi-e164 [Nov 30 20:51:46] -- Added extension 's' priority 1 to macro-dundi-e164 [Nov 30 20:51:46] -- Including context 'dundi-e164-lookup' in context 'macro-dundi-e164' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'iaxtel700' [Nov 30 20:51:46] -- Registered extension context 'iaxtel700' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_91700XXXXXXX' priority 1 to iaxtel700 [Nov 30 20:51:46] -- Added extension '_91700XXXXXXX' priority 1 to iaxtel700 [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'iaxprovider' [Nov 30 20:51:46] -- Registered extension context 'iaxprovider' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'trunkint' [Nov 30 20:51:46] -- Registered extension context 'trunkint' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_9011.' priority 1 to trunkint [Nov 30 20:51:46] -- Added extension '_9011.' priority 1 to trunkint [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '_9011.' priority 2 to trunkint [Nov 30 20:51:46] -- Added extension '_9011.' priority 2 to trunkint [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'trunkld' [Nov 30 20:51:46] -- Registered extension context 'trunkld' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_91NXXNXXXXXX' priority 1 to trunkld [Nov 30 20:51:46] -- Added extension '_91NXXNXXXXXX' priority 1 to trunkld [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '_91NXXNXXXXXX' priority 2 to trunkld [Nov 30 20:51:46] -- Added extension '_91NXXNXXXXXX' priority 2 to trunkld [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'trunklocal' [Nov 30 20:51:46] -- Registered extension context 'trunklocal' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_9NXXXXXX' priority 1 to trunklocal [Nov 30 20:51:46] -- Added extension '_9NXXXXXX' priority 1 to trunklocal [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'trunktollfree' [Nov 30 20:51:46] -- Registered extension context 'trunktollfree' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_91800NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] -- Added extension '_91800NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_91888NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] -- Added extension '_91888NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '_91877NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] -- Added extension '_91877NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '_91866NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] -- Added extension '_91866NXXXXXX' priority 1 to trunktollfree [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'international' [Nov 30 20:51:46] -- Registered extension context 'international' [Nov 30 20:51:46] -- Including context 'longdistance' in context 'international' [Nov 30 20:51:46] -- Including context 'trunkint' in context 'international' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'longdistance' [Nov 30 20:51:46] -- Registered extension context 'longdistance' [Nov 30 20:51:46] -- Including context 'local' in context 'longdistance' [Nov 30 20:51:46] -- Including context 'trunkld' in context 'longdistance' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'local' [Nov 30 20:51:46] -- Registered extension context 'local' [Nov 30 20:51:46] -- Including context 'default' in context 'local' [Nov 30 20:51:46] -- Including context 'parkedcalls' in context 'local' [Nov 30 20:51:46] -- Including context 'trunklocal' in context 'local' [Nov 30 20:51:46] -- Including context 'iaxtel700' in context 'local' [Nov 30 20:51:46] -- Including context 'trunktollfree' in context 'local' [Nov 30 20:51:46] -- Including context 'iaxprovider' in context 'local' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'macro-stdexten' [Nov 30 20:51:46] -- Registered extension context 'macro-stdexten' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's' priority 1 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's' priority 2 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's-NOANSWER' priority 1 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's-NOANSWER' priority 1 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's-NOANSWER' priority 2 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's-NOANSWER' priority 2 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 's-BUSY' priority 1 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's-BUSY' priority 1 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's-BUSY' priority 2 to macro-stdexten [Nov 30 20:51:46] -- Added extension 's-BUSY' priority 2 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_s-.' priority 1 to macro-stdexten [Nov 30 20:51:46] -- Added extension '_s-.' priority 1 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 'a' priority 1 to macro-stdexten [Nov 30 20:51:46] -- Added extension 'a' priority 1 to macro-stdexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'macro-stdPrivacyexten' [Nov 30 20:51:46] -- Registered extension context 'macro-stdPrivacyexten' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's-NOANSWER' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-NOANSWER' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's-NOANSWER' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-NOANSWER' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 's-BUSY' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-BUSY' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's-BUSY' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-BUSY' priority 2 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 's-DONTCALL' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-DONTCALL' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's-TORTURE' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 's-TORTURE' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_s-.' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension '_s-.' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 'a' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] -- Added extension 'a' priority 1 to macro-stdPrivacyexten [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'demo' [Nov 30 20:51:46] -- Registered extension context 'demo' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to demo [Nov 30 20:51:46] -- Added extension 's' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to demo [Nov 30 20:51:46] -- Added extension 's' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to demo [Nov 30 20:51:46] -- Added extension 's' priority 3 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to demo [Nov 30 20:51:46] -- Added extension 's' priority 4 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to demo [Nov 30 20:51:46] -- Added extension 's' priority 5 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 6 to demo [Nov 30 20:51:46] -- Added extension 's' priority 6 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 7 to demo [Nov 30 20:51:46] -- Added extension 's' priority 7 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '2' priority 1 to demo [Nov 30 20:51:46] -- Added extension '2' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '2' priority 2 to demo [Nov 30 20:51:46] -- Added extension '2' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '3' priority 1 to demo [Nov 30 20:51:46] -- Added extension '3' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '3' priority 2 to demo [Nov 30 20:51:46] -- Added extension '3' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '1000' priority 1 to demo [Nov 30 20:51:46] -- Added extension '1000' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '1234' priority 1 to demo [Nov 30 20:51:46] -- Added extension '1234' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '1234' priority 2 to demo [Nov 30 20:51:46] -- Added extension '1234' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '1235' priority 1 to demo [Nov 30 20:51:46] -- Added extension '1235' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '1236' priority 1 to demo [Nov 30 20:51:46] -- Added extension '1236' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '1236' priority 2 to demo [Nov 30 20:51:46] -- Added extension '1236' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '#' priority 1 to demo [Nov 30 20:51:46] -- Added extension '#' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '#' priority 2 to demo [Nov 30 20:51:46] -- Added extension '#' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 't' priority 1 to demo [Nov 30 20:51:46] -- Added extension 't' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 'i' priority 1 to demo [Nov 30 20:51:46] -- Added extension 'i' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '500' priority 1 to demo [Nov 30 20:51:46] -- Added extension '500' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 2 to demo [Nov 30 20:51:46] -- Added extension '500' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 3 to demo [Nov 30 20:51:46] -- Added extension '500' priority 3 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 4 to demo [Nov 30 20:51:46] -- Added extension '500' priority 4 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '600' priority 1 to demo [Nov 30 20:51:46] -- Added extension '600' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 2 to demo [Nov 30 20:51:46] -- Added extension '600' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 3 to demo [Nov 30 20:51:46] -- Added extension '600' priority 3 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 4 to demo [Nov 30 20:51:46] -- Added extension '600' priority 4 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '8500' priority 1 to demo [Nov 30 20:51:46] -- Added extension '8500' priority 1 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '8500' priority 2 to demo [Nov 30 20:51:46] -- Added extension '8500' priority 2 to demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'default' [Nov 30 20:51:46] -- Registered extension context 'default' [Nov 30 20:51:46] -- Including context 'demo' in context 'default' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'test' [Nov 30 20:51:46] -- Registered extension context 'test' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to test [Nov 30 20:51:46] -- Added extension 's' priority 1 to test [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's2' priority 1 to test [Nov 30 20:51:46] -- Added extension 's2' priority 1 to test [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's3' priority 1 to test [Nov 30 20:51:46] -- Added extension 's3' priority 1 to test [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's3' priority 2 to test [Nov 30 20:51:46] -- Added extension 's3' priority 2 to test [Nov 30 20:51:46] [pbx_spool.so][Nov 30 20:51:46] => (Outgoing Spool Support) [Nov 30 20:51:46] [pbx_dundi.so][Nov 30 20:51:46] => (Distributed Universal Number Discovery (DUNDi)) [Nov 30 20:51:46] == Parsing '/etc/asterisk/dundi.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/dundi.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: pbx_dundi.c:422 reset_global_eid: Seeding global EID '00:0c:6e:30:96:63' from 'eth0' [Nov 30 20:51:46] == Using TOS bits 0 [Nov 30 20:51:46] == DUNDi Ready and Listening on 0.0.0.0 port 4520 [Nov 30 20:51:46] == Registered application 'DUNDiLookup' [Nov 30 20:51:46] == Registered custom function DUNDILOOKUP [Nov 30 20:51:46] [pbx_loopback.so][Nov 30 20:51:46] => (Loopback Switch) [Nov 30 20:51:46] [pbx_realtime.so][Nov 30 20:51:46] => (Realtime Switch) [Nov 30 20:51:46] [pbx_ael.so][Nov 30 20:51:46] => (Asterisk Extension Language Compiler) [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'macro-std-exten-ael' [Nov 30 20:51:46] -- Registered extension context 'macro-std-exten-ael' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 's' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 's' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 's' priority 3 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 's' priority 4 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 's' priority 5 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 'a' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 'a' priority 1 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 'a' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] -- Added extension 'a' priority 2 to macro-std-exten-ael [Nov 30 20:51:46] DEBUG[2215]: pbx.c:3674 ast_context_create: Registered context 'ael-demo' [Nov 30 20:51:46] -- Registered extension context 'ael-demo' [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension 's' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 3 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 3 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 4 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 4 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 5 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 5 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 6 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 6 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 8 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 8 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 9 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 9 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 10 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 10 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 11 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 11 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension 's' priority 12 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 12 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4828 ast_add_extension2: Added extension 's' priority 7 to ael-demo [Nov 30 20:51:46] -- Added extension 's' priority 7 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '2' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '2' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '2' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension '2' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '3' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '3' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '3' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension '3' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '500' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '500' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension '500' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 3 to ael-demo [Nov 30 20:51:46] -- Added extension '500' priority 3 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '500' priority 4 to ael-demo [Nov 30 20:51:46] -- Added extension '500' priority 4 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '600' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '600' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension '600' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 3 to ael-demo [Nov 30 20:51:46] -- Added extension '600' priority 3 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '600' priority 4 to ael-demo [Nov 30 20:51:46] -- Added extension '600' priority 4 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4876 ast_add_extension2: Added extension '_1234' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '_1234' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension '#' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension '#' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4842 ast_add_extension2: Added extension '#' priority 2 to ael-demo [Nov 30 20:51:46] -- Added extension '#' priority 2 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 't' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension 't' priority 1 to ael-demo [Nov 30 20:51:46] DEBUG[2215]: pbx.c:4861 ast_add_extension2: Added extension 'i' priority 1 to ael-demo [Nov 30 20:51:46] -- Added extension 'i' priority 1 to ael-demo [Nov 30 20:51:46] [pbx_functions.so][Nov 30 20:51:46] => (Builtin dialplan functions) [Nov 30 20:51:46] == Registered custom function MD5 [Nov 30 20:51:46] == Registered custom function CHECK_MD5 [Nov 30 20:51:46] == Registered custom function MATH [Nov 30 20:51:46] == Registered custom function GROUP_COUNT [Nov 30 20:51:46] == Registered custom function GROUP_MATCH_COUNT [Nov 30 20:51:46] == Registered custom function GROUP [Nov 30 20:51:46] == Registered custom function GROUP_LIST [Nov 30 20:51:46] == Registered custom function FIELDQTY [Nov 30 20:51:46] == Registered custom function REGEX [Nov 30 20:51:46] == Registered custom function LEN [Nov 30 20:51:46] == Registered custom function STRFTIME [Nov 30 20:51:46] == Registered custom function EVAL [Nov 30 20:51:46] == Registered custom function CDR [Nov 30 20:51:46] == Registered custom function ISNULL [Nov 30 20:51:46] == Registered custom function SET [Nov 30 20:51:46] == Registered custom function EXISTS [Nov 30 20:51:46] == Registered custom function IF [Nov 30 20:51:46] == Registered custom function IFTIME [Nov 30 20:51:46] == Registered custom function ENV [Nov 30 20:51:46] == Registered custom function DB [Nov 30 20:51:46] == Registered custom function DB_EXISTS [Nov 30 20:51:46] == Registered custom function TIMEOUT [Nov 30 20:51:46] == Registered custom function LANGUAGE [Nov 30 20:51:46] == Registered custom function MUSICCLASS [Nov 30 20:51:46] [chan_sip.so][Nov 30 20:51:46] => (Session Initiation Protocol (SIP)) [Nov 30 20:51:46] == Parsing '/etc/asterisk/sip.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/sip.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == SIP Listening on 0.0.0.0:5060 [Nov 30 20:51:46] == Using TOS bits 0 [Nov 30 20:51:46] == Parsing '/etc/asterisk/sip_notify.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) [Nov 30 20:51:46] == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) [Nov 30 20:51:46] == Registered application 'SIPDtmfMode' [Nov 30 20:51:46] == Registered application 'SIPAddHeader' [Nov 30 20:51:46] == Registered application 'SIPGetHeader' [Nov 30 20:51:46] == Registered custom function SIP_HEADER [Nov 30 20:51:46] == Registered custom function SIPPEER [Nov 30 20:51:46] == Registered custom function SIPCHANINFO [Nov 30 20:51:46] == Registered custom function CHECKSIPDOMAIN [Nov 30 20:51:46] == Manager registered action SIPpeers [Nov 30 20:51:46] == Manager registered action SIPshowpeer [Nov 30 20:51:46] [chan_agent.so][Nov 30 20:51:46] => (Agent Proxy Channel) [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Agent' (Call Agent Proxy Channel) [Nov 30 20:51:46] == Registered channel type 'Agent' (Call Agent Proxy Channel) [Nov 30 20:51:46] == Registered application 'AgentLogin' [Nov 30 20:51:46] == Registered application 'AgentCallbackLogin' [Nov 30 20:51:46] == Registered application 'AgentMonitorOutgoing' [Nov 30 20:51:46] == Manager registered action Agents [Nov 30 20:51:46] == Manager registered action AgentLogoff [Nov 30 20:51:46] == Manager registered action AgentCallbackLogin [Nov 30 20:51:46] == Parsing '/etc/asterisk/agents.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/agents.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [chan_mgcp.so][Nov 30 20:51:46] => (Media Gateway Control Protocol (MGCP)) [Nov 30 20:51:46] == Parsing '/etc/asterisk/mgcp.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/mgcp.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == MGCP Listening on 0.0.0.0:2727 [Nov 30 20:51:46] == Using TOS bits 0 [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) [Nov 30 20:51:46] == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) [Nov 30 20:51:46] [chan_iax2.so][Nov 30 20:51:46] => (Inter Asterisk eXchange (Ver 2)) [Nov 30 20:51:46] == Registered custom function IAXPEER [Nov 30 20:51:46] == Registered application 'IAX2Provision' [Nov 30 20:51:46] == Manager registered action IAXpeers [Nov 30 20:51:46] == Manager registered action IAXnetstats [Nov 30 20:51:46] == Parsing '/etc/asterisk/iax.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iax.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Using TOS bits 16 [Nov 30 20:51:46] == Binding IAX2 to default address 0.0.0.0:4569 [Nov 30 20:51:46] -- doing lookup for '216.207.245.47' [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Nov 30 20:51:46] == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Nov 30 20:51:46] == IAX Ready and Listening [Nov 30 20:51:46] == Loaded firmware 'iaxy.bin' [Nov 30 20:51:46] == Parsing '/etc/asterisk/iaxprov.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/iaxprov.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- Loaded provisioning template 'default' [Nov 30 20:51:46] [chan_local.so][Nov 30 20:51:46] => (Local Proxy Channel) [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Local' (Local Proxy Channel Driver) [Nov 30 20:51:46] == Registered channel type 'Local' (Local Proxy Channel Driver) [Nov 30 20:51:46] [chan_skinny.so][Nov 30 20:51:46] => (Skinny Client Control Protocol (Skinny)) [Nov 30 20:51:46] == Parsing '/etc/asterisk/skinny.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/skinny.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Skinny listening on 0.0.0.0:2000 [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) [Nov 30 20:51:46] == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) [Nov 30 20:51:46] [chan_features.so][Nov 30 20:51:46] => (Feature Proxy Channel) [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Feature' (Feature Proxy Channel Driver) [Nov 30 20:51:46] == Registered channel type 'Feature' (Feature Proxy Channel Driver) [Nov 30 20:51:46] [chan_oss.so][Nov 30 20:51:46] => (OSS Console Channel Driver) [Nov 30 20:51:46] == Parsing '/etc/asterisk/oss.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/oss.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Console' (OSS Console Channel Driver) [Nov 30 20:51:46] == Registered channel type 'Console' (OSS Console Channel Driver) [Nov 30 20:51:46] [chan_phone.so][Nov 30 20:51:46] => (Linux Telephony API Support) [Nov 30 20:51:46] == Parsing '/etc/asterisk/phone.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/phone.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: channel.c:344 ast_channel_register: Registered handler for 'Phone' (Standard Linux Telephony API Driver) [Nov 30 20:51:46] == Registered channel type 'Phone' (Standard Linux Telephony API Driver) [Nov 30 20:51:46] [app_dial.so][Nov 30 20:51:46] => (Dialing Application) [Nov 30 20:51:46] == Registered application 'Dial' [Nov 30 20:51:46] == Registered application 'RetryDial' [Nov 30 20:51:46] [app_playback.so][Nov 30 20:51:46] => (Sound File Playback Application) [Nov 30 20:51:46] == Registered application 'Playback' [Nov 30 20:51:46] [app_voicemail.so][Nov 30 20:51:46] => (Comedian Mail (Voicemail System)) [Nov 30 20:51:46] == Registered application 'VoiceMail' [Nov 30 20:51:46] == Registered application 'VoiceMailMain' [Nov 30 20:51:46] == Registered application 'MailboxExists' [Nov 30 20:51:46] == Registered application 'VMAuthenticate' [Nov 30 20:51:46] == Parsing '/etc/asterisk/voicemail.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/voicemail.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6006 load_config: VM Review Option disabled globally [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6012 load_config: VM Operator break disabled globally [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6018 load_config: VM CID Info before msg disabled globally [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6030 load_config: ENVELOPE before msg enabled globally [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6036 load_config: Duration info before msg enabled globally [Nov 30 20:51:46] DEBUG[2215]: app_voicemail.c:6051 load_config: We are not going to skip to the next msg after save/delete [Nov 30 20:51:46] [app_directory.so][Nov 30 20:51:46] => (Extension Directory) [Nov 30 20:51:46] == Registered application 'Directory' [Nov 30 20:51:46] [app_mp3.so][Nov 30 20:51:46] => (Silly MP3 Application) [Nov 30 20:51:46] == Registered application 'MP3Player' [Nov 30 20:51:46] [app_system.so][Nov 30 20:51:46] => (Generic System() application) [Nov 30 20:51:46] == Registered application 'TrySystem' [Nov 30 20:51:46] == Registered application 'System' [Nov 30 20:51:46] [app_echo.so][Nov 30 20:51:46] => (Simple Echo Application) [Nov 30 20:51:46] == Registered application 'Echo' [Nov 30 20:51:46] [app_record.so][Nov 30 20:51:46] => (Trivial Record Application) [Nov 30 20:51:46] == Registered application 'Record' [Nov 30 20:51:46] [app_image.so][Nov 30 20:51:46] => (Image Transmission Application) [Nov 30 20:51:46] == Registered application 'SendImage' [Nov 30 20:51:46] [app_url.so][Nov 30 20:51:46] => (Send URL Applications) [Nov 30 20:51:46] == Registered application 'SendURL' [Nov 30 20:51:46] [app_disa.so][Nov 30 20:51:46] => (DISA (Direct Inward System Access) Application) [Nov 30 20:51:46] == Registered application 'DISA' [Nov 30 20:51:46] [app_adsiprog.so][Nov 30 20:51:46] => (Asterisk ADSI Programming Application) [Nov 30 20:51:46] == Registered application 'ADSIProg' [Nov 30 20:51:46] [app_getcpeid.so][Nov 30 20:51:46] => (Get ADSI CPE ID) [Nov 30 20:51:46] == Registered application 'GetCPEID' [Nov 30 20:51:46] [app_milliwatt.so][Nov 30 20:51:46] => (Digital Milliwatt (mu-law) Test Application) [Nov 30 20:51:46] == Registered application 'Milliwatt' [Nov 30 20:51:46] [app_zapateller.so][Nov 30 20:51:46] => (Block Telemarketers with Special Information Tone) [Nov 30 20:51:46] == Registered application 'Zapateller' [Nov 30 20:51:46] [app_setcallerid.so][Nov 30 20:51:46] => (Set CallerID Application) [Nov 30 20:51:46] == Registered application 'SetCallerPres' [Nov 30 20:51:46] == Registered application 'SetCallerID' [Nov 30 20:51:46] [app_festival.so][Nov 30 20:51:46] => (Simple Festival Interface) [Nov 30 20:51:46] == Registered application 'Festival' [Nov 30 20:51:46] [app_queue.so][Nov 30 20:51:46] => (True Call Queueing) [Nov 30 20:51:46] == Registered application 'Queue' [Nov 30 20:51:46] == Manager registered action Queues [Nov 30 20:51:46] == Manager registered action QueueStatus [Nov 30 20:51:46] == Manager registered action QueueAdd [Nov 30 20:51:46] == Manager registered action QueueRemove [Nov 30 20:51:46] == Manager registered action QueuePause [Nov 30 20:51:46] == Registered application 'AddQueueMember' [Nov 30 20:51:46] == Registered application 'RemoveQueueMember' [Nov 30 20:51:46] == Registered application 'PauseQueueMember' [Nov 30 20:51:46] == Registered application 'UnpauseQueueMember' [Nov 30 20:51:46] == Registered custom function QUEUEAGENTCOUNT [Nov 30 20:51:46] == Parsing '/etc/asterisk/queues.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/queues.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [app_senddtmf.so][Nov 30 20:51:46] => (Send DTMF digits Application) [Nov 30 20:51:46] == Registered application 'SendDTMF' [Nov 30 20:51:46] [app_parkandannounce.so][Nov 30 20:51:46] => (Call Parking and Announce Application) [Nov 30 20:51:46] == Registered application 'ParkAndAnnounce' [Nov 30 20:51:46] [app_setcidname.so][Nov 30 20:51:46] => (Set CallerID Name) [Nov 30 20:51:46] == Registered application 'SetCIDName' [Nov 30 20:51:46] [app_lookupcidname.so][Nov 30 20:51:46] => (Look up CallerID Name from local database) [Nov 30 20:51:46] == Registered application 'LookupCIDName' [Nov 30 20:51:46] [app_macro.so][Nov 30 20:51:46] => (Extension Macros) [Nov 30 20:51:46] == Registered application 'MacroExit' [Nov 30 20:51:46] == Registered application 'MacroIf' [Nov 30 20:51:46] == Registered application 'Macro' [Nov 30 20:51:46] [app_authenticate.so][Nov 30 20:51:46] => (Authentication Application) [Nov 30 20:51:46] == Registered application 'Authenticate' [Nov 30 20:51:46] [app_softhangup.so][Nov 30 20:51:46] => (Hangs up the requested channel) [Nov 30 20:51:46] == Registered application 'SoftHangup' [Nov 30 20:51:46] [app_lookupblacklist.so][Nov 30 20:51:46] => (Look up Caller*ID name/number from blacklist database) [Nov 30 20:51:46] == Registered application 'LookupBlacklist' [Nov 30 20:51:46] [app_waitforring.so][Nov 30 20:51:46] => (Waits until first ring after time) [Nov 30 20:51:46] == Registered application 'WaitForRing' [Nov 30 20:51:46] [app_privacy.so][Nov 30 20:51:46] => (Require phone number to be entered, if no CallerID sent) [Nov 30 20:51:46] == Registered application 'PrivacyManager' [Nov 30 20:51:46] [app_db.so][Nov 30 20:51:46] => (Database Access Functions) [Nov 30 20:51:46] == Registered application 'DBget' [Nov 30 20:51:46] == Registered application 'DBput' [Nov 30 20:51:46] == Registered application 'DBdel' [Nov 30 20:51:46] == Registered application 'DBdeltree' [Nov 30 20:51:46] [app_chanisavail.so][Nov 30 20:51:46] => (Check channel availability) [Nov 30 20:51:46] == Registered application 'ChanIsAvail' [Nov 30 20:51:46] [app_enumlookup.so][Nov 30 20:51:46] => (ENUM Lookup) [Nov 30 20:51:46] == Registered application 'EnumLookup' [Nov 30 20:51:46] == Parsing '/etc/asterisk/enum.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/enum.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [app_transfer.so][Nov 30 20:51:46] => (Transfer) [Nov 30 20:51:46] == Registered application 'Transfer' [Nov 30 20:51:46] [app_setcidnum.so][Nov 30 20:51:46] => (Set CallerID Number) [Nov 30 20:51:46] == Registered application 'SetCIDNum' [Nov 30 20:51:46] [app_cdr.so][Nov 30 20:51:46] => (Tell Asterisk to not maintain a CDR for the current call) [Nov 30 20:51:46] == Registered application 'NoCDR' [Nov 30 20:51:46] [app_hasnewvoicemail.so][Nov 30 20:51:46] => (Indicator for whether a voice mailbox has messages in a given folder.[) [Nov 30 20:51:46] == Registered custom function VMCOUNT [Nov 30 20:51:46] == Registered application 'HasVoicemail' [Nov 30 20:51:46] == Registered application 'HasNewVoicemail' [Nov 30 20:51:46] [app_sayunixtime.so][Nov 30 20:51:46] => (Say time) [Nov 30 20:51:46] == Registered application 'SayUnixTime' [Nov 30 20:51:46] == Registered application 'DateTime' [Nov 30 20:51:46] [app_cut.so][Nov 30 20:51:46] => (Cut out information from a string) [Nov 30 20:51:46] == Registered custom function CUT [Nov 30 20:51:46] == Registered custom function SORT [Nov 30 20:51:46] == Registered application 'Sort' [Nov 30 20:51:46] == Registered application 'Cut' [Nov 30 20:51:46] [app_read.so][Nov 30 20:51:46] => (Read Variable Application) [Nov 30 20:51:46] == Registered application 'Read' [Nov 30 20:51:46] [app_setcdruserfield.so][Nov 30 20:51:46] => (CDR user field apps) [Nov 30 20:51:46] == Registered application 'SetCDRUserField' [Nov 30 20:51:46] == Registered application 'AppendCDRUserField' [Nov 30 20:51:46] == Manager registered action SetCDRUserField [Nov 30 20:51:46] [app_random.so][Nov 30 20:51:46] => (Random goto) [Nov 30 20:51:46] == Registered application 'Random' [Nov 30 20:51:46] [app_ices.so][Nov 30 20:51:46] => (Encode and Stream via icecast and ices) [Nov 30 20:51:46] == Registered application 'ICES' [Nov 30 20:51:46] [app_eval.so][Nov 30 20:51:46] => (Reevaluates strings) [Nov 30 20:51:46] == Registered application 'Eval' [Nov 30 20:51:46] [app_nbscat.so][Nov 30 20:51:46] => (Silly NBS Stream Application) [Nov 30 20:51:46] == Registered application 'NBScat' [Nov 30 20:51:46] [app_sendtext.so][Nov 30 20:51:46] => (Send Text Applications) [Nov 30 20:51:46] == Registered application 'SendText' [Nov 30 20:51:46] [app_exec.so][Nov 30 20:51:46] => (Executes applications) [Nov 30 20:51:46] == Registered application 'Exec' [Nov 30 20:51:46] [app_groupcount.so][Nov 30 20:51:46] => (Group Management Routines) [Nov 30 20:51:46] == Registered application 'GetGroupCount' [Nov 30 20:51:46] == Registered application 'SetGroup' [Nov 30 20:51:46] == Registered application 'CheckGroup' [Nov 30 20:51:46] == Registered application 'GetGroupMatchCount' [Nov 30 20:51:46] [app_txtcidname.so][Nov 30 20:51:46] => (TXTCIDName) [Nov 30 20:51:46] == Registered application 'TXTCIDName' [Nov 30 20:51:46] [app_controlplayback.so][Nov 30 20:51:46] => (Control Playback Application) [Nov 30 20:51:46] == Registered application 'ControlPlayback' [Nov 30 20:51:46] [app_talkdetect.so][Nov 30 20:51:46] => (Playback with Talk Detection) [Nov 30 20:51:46] == Registered application 'BackgroundDetect' [Nov 30 20:51:46] [app_alarmreceiver.so][Nov 30 20:51:46] => (Alarm Receiver for Asterisk) [Nov 30 20:51:46] == Parsing '/etc/asterisk/alarmreceiver.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/alarmreceiver.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] == Registered application 'AlarmReceiver' [Nov 30 20:51:46] [app_userevent.so][Nov 30 20:51:46] => (Custom User Event Application) [Nov 30 20:51:46] == Registered application 'UserEvent' [Nov 30 20:51:46] [app_verbose.so][Nov 30 20:51:46] => (Send verbose output) [Nov 30 20:51:46] == Registered application 'Verbose' [Nov 30 20:51:46] [app_test.so][Nov 30 20:51:46] => (Interface Test Application) [Nov 30 20:51:46] == Registered application 'TestClient' [Nov 30 20:51:46] == Registered application 'TestServer' [Nov 30 20:51:46] [app_forkcdr.so][Nov 30 20:51:46] => (Fork The CDR into 2 separate entities.) [Nov 30 20:51:46] == Registered application 'ForkCDR' [Nov 30 20:51:46] [app_math.so][Nov 30 20:51:46] => (Basic Math Functions) [Nov 30 20:51:46] == Registered application 'Math' [Nov 30 20:51:46] [app_realtime.so][Nov 30 20:51:46] => (Realtime Data Lookup/Rewrite) [Nov 30 20:51:46] == Registered application 'RealTimeUpdate' [Nov 30 20:51:46] == Registered application 'RealTime' [Nov 30 20:51:46] [app_dumpchan.so][Nov 30 20:51:46] => (Dump Info About The Calling Channel) [Nov 30 20:51:46] == Registered application 'DumpChan' [Nov 30 20:51:46] [app_waitforsilence.so][Nov 30 20:51:46] => (Wait For Silence) [Nov 30 20:51:46] == Registered application 'WaitForSilence' [Nov 30 20:51:46] [app_while.so][Nov 30 20:51:46] => (While Loops and Conditional Execution) [Nov 30 20:51:46] == Registered application 'While' [Nov 30 20:51:46] == Registered application 'ExecIf' [Nov 30 20:51:46] == Registered application 'EndWhile' [Nov 30 20:51:46] [app_setrdnis.so][Nov 30 20:51:46] => (Set RDNIS Number) [Nov 30 20:51:46] == Registered application 'SetRDNIS' [Nov 30 20:51:46] [app_md5.so][Nov 30 20:51:46] => (MD5 checksum applications) [Nov 30 20:51:46] == Registered application 'MD5Check' [Nov 30 20:51:46] == Registered application 'MD5' [Nov 30 20:51:46] [app_readfile.so][Nov 30 20:51:46] => (Stores output of file into a variable) [Nov 30 20:51:46] == Registered application 'ReadFile' [Nov 30 20:51:46] [app_chanspy.so][Nov 30 20:51:46] => (Listen to the audio of an active channel ) [Nov 30 20:51:46] == Registered application 'ChanSpy' [Nov 30 20:51:46] [app_settransfercapability.so][Nov 30 20:51:46] => (Set ISDN Transfer Capability) [Nov 30 20:51:46] == Registered application 'SetTransferCapability' [Nov 30 20:51:46] [app_dictate.so][Nov 30 20:51:46] => (Virtual Dictation Machine) [Nov 30 20:51:46] == Registered application 'Dictate' [Nov 30 20:51:46] [app_externalivr.so][Nov 30 20:51:46] => (External IVR Interface Application) [Nov 30 20:51:46] == Registered application 'ExternalIVR' [Nov 30 20:51:46] [app_directed_pickup.so][Nov 30 20:51:46] => (Directed Call Pickup Application) [Nov 30 20:51:46] == Registered application 'Pickup' [Nov 30 20:51:46] [app_mixmonitor.so][Nov 30 20:51:46] => (Mixed Audio Monitoring Application) [Nov 30 20:51:46] == Registered application 'MixMonitor' [Nov 30 20:51:46] [app_stack.so][Nov 30 20:51:46] => (Stack Routines) [Nov 30 20:51:46] == Registered application 'StackPop' [Nov 30 20:51:46] == Registered application 'Return' [Nov 30 20:51:46] == Registered application 'GosubIf' [Nov 30 20:51:46] == Registered application 'Gosub' [Nov 30 20:51:46] [app_sms.so][Nov 30 20:51:46] => (SMS/PSTN handler) [Nov 30 20:51:46] == Registered application 'SMS' [Nov 30 20:51:46] [codec_ilbc.so][Nov 30 20:51:46] => (iLBC/PCM16 (signed linear) Codec Translator) [Nov 30 20:51:46] == Registered translator 'ilbctolin' from format ilbc to slin, cost 3 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] == Registered translator 'lintoilbc' from format slin to ilbc, cost 18 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] [codec_gsm.so][Nov 30 20:51:46] => (GSM/PCM16 (signed linear) Codec Translator) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_gsm: using generic PLC [Nov 30 20:51:46] == Registered translator 'gsmtolin' from format gsm to slin, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'lintogsm' from format slin to gsm, cost 3 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] [codec_lpc10.so][Nov 30 20:51:46] => (LPC10 2.4kbps (signed linear) Voice Coder) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_lpc10: using generic PLC [Nov 30 20:51:46] == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] == Registered translator 'lintolpc10' from format slin to lpc10, cost 3 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [codec_adpcm.so][Nov 30 20:51:46] => (Adaptive Differential PCM Coder/Decoder) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_adpcm: using generic PLC [Nov 30 20:51:46] == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [codec_ulaw.so][Nov 30 20:51:46] => (Mu-law Coder/Decoder) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_ulaw: using generic PLC [Nov 30 20:51:46] == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [codec_alaw.so][Nov 30 20:51:46] => (A-law Coder/Decoder) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_alaw: using generic PLC [Nov 30 20:51:46] == Registered translator 'alawtolin' from format alaw to slin, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'lintoalaw' from format slin to alaw, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [codec_a_mu.so][Nov 30 20:51:46] => (A-law and Mulaw direct Coder/Decoder) [Nov 30 20:51:46] == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [codec_g726.so][Nov 30 20:51:46] => (ITU G.726-32kbps G726 Transcoder) [Nov 30 20:51:46] == Parsing '/etc/asterisk/codecs.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] -- codec_g726: using generic PLC [Nov 30 20:51:46] == Registered translator 'g726tolin' from format g726 to slin, cost 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from ulaw to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] == Registered translator 'lintog726' from format slin to g726, cost 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:278 rebuild_matrix: Resetting translation matrix [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from g723 to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from gsm to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 2 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from ulaw to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from ulaw to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 19 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 20 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to g723, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to gsm, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 5 cost path from unknown to ulaw, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] DEBUG[2215]: translate.c:322 rebuild_matrix: Discovered 6 cost path from unknown to unknown, via 6 [Nov 30 20:51:46] [format_gsm.so][Nov 30 20:51:46] => (Raw GSM data) [Nov 30 20:51:46] == Registered file format gsm, extension(s) gsm [Nov 30 20:51:46] [format_wav.so][Nov 30 20:51:46] => (Microsoft WAV format (8000hz Signed Linear)) [Nov 30 20:51:46] == Registered file format wav, extension(s) wav [Nov 30 20:51:46] [format_wav_gsm.so][Nov 30 20:51:46] => (Microsoft WAV format (Proprietary GSM)) [Nov 30 20:51:46] == Registered file format wav49, extension(s) WAV|wav49 [Nov 30 20:51:46] [format_vox.so][Nov 30 20:51:46] => (Dialogic VOX (ADPCM) File Format) [Nov 30 20:51:46] == Registered file format vox, extension(s) vox [Nov 30 20:51:46] [format_pcm.so][Nov 30 20:51:46] => (Raw uLaw 8khz Audio support (PCM)) [Nov 30 20:51:46] == Registered file format pcm, extension(s) pcm|ulaw|ul|mu [Nov 30 20:51:46] [format_g729.so][Nov 30 20:51:46] => (Raw G729 data) [Nov 30 20:51:46] == Registered file format g729, extension(s) g729 [Nov 30 20:51:46] [format_pcm_alaw.so][Nov 30 20:51:46] => (Raw aLaw 8khz PCM Audio support) [Nov 30 20:51:46] == Registered file format alaw, extension(s) alaw|al [Nov 30 20:51:46] [format_h263.so][Nov 30 20:51:46] => (Raw h263 data) [Nov 30 20:51:46] == Registered file format h263, extension(s) h263 [Nov 30 20:51:46] [format_g726.so][Nov 30 20:51:46] => (Raw G.726 (16/24/32/40kbps) data) [Nov 30 20:51:46] == Registered file format g726-40, extension(s) g726-40 [Nov 30 20:51:46] == Registered file format g726-32, extension(s) g726-32 [Nov 30 20:51:46] == Registered file format g726-24, extension(s) g726-24 [Nov 30 20:51:46] == Registered file format g726-16, extension(s) g726-16 [Nov 30 20:51:46] [format_ilbc.so][Nov 30 20:51:46] => (Raw iLBC data) [Nov 30 20:51:46] == Registered file format iLBC, extension(s) ilbc [Nov 30 20:51:46] [format_sln.so][Nov 30 20:51:46] => (Raw Signed Linear Audio support (SLN)) [Nov 30 20:51:46] == Registered file format sln, extension(s) sln|raw [Nov 30 20:51:46] [format_au.so][Nov 30 20:51:46] => (Sun Microsystems AU format (signed linear)) [Nov 30 20:51:46] == Registered file format au, extension(s) au [Nov 30 20:51:46] [format_jpeg.so][Nov 30 20:51:46] => (JPEG (Joint Picture Experts Group) Image Format) [Nov 30 20:51:46] == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) [Nov 30 20:51:46] [format_g723.so][Nov 30 20:51:46] => (G.723.1 Simple Timestamp File Format) [Nov 30 20:51:46] == Registered file format g723sf, extension(s) g723|g723sf [Nov 30 20:51:46] [cdr_csv.so][Nov 30 20:51:46] => (Comma Separated Values CDR Backend) [Nov 30 20:51:46] [cdr_manager.so][Nov 30 20:51:46] => (Asterisk Call Manager CDR Backend) [Nov 30 20:51:46] == Parsing '/etc/asterisk/cdr_manager.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/cdr_manager.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [cdr_custom.so][Nov 30 20:51:46] => (Customizable Comma Separated Values CDR Backend) [Nov 30 20:51:46] == Parsing '/etc/asterisk/cdr_custom.conf': [Nov 30 20:51:46] DEBUG[2215]: config.c:595 config_text_file_load: Parsing /etc/asterisk/cdr_custom.conf [Nov 30 20:51:46] Found [Nov 30 20:51:46] [func_callerid.so][Nov 30 20:51:46] => (Caller ID related dialplan function) [Nov 30 20:51:46] == Registered custom function CALLERID [Nov 30 20:51:46] [func_enum.so][Nov 30 20:51:46] => (ENUM Related Functions) [Nov 30 20:51:46] == Registered custom function ENUMLOOKUP [Nov 30 20:51:46] == Registered custom function TXTCIDNAME [Nov 30 20:51:46] [func_uri.so][Nov 30 20:51:46] => (URI encode/decode functions) [Nov 30 20:51:46] == Registered custom function URIDECODE [Nov 30 20:51:46] == Registered custom function URIENCODE [Nov 30 20:51:46] Asterisk Ready. ]1;Asterisk]2;Asterisk Console on 'test' (pid 2215)*CLI> sip debug SIP Debugging enabled *CLI> [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5060: INVITE sip:s@192.168.101.247:5060 SIP/2.0 Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0 From: sipp ;tag=10270SIPpTag001 To: sut Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE Contact: sip:sipp@127.0.0.1:5060 Max-Forwards: 70 Subject: Performance Test Content-Type: application/sdp Content-Length: 129 v=0 o=user1 53655765 2353687637 IN IP4 127.0.0.1 s=- c=IN IP4 127.0.0.1 t=0 0 m=audio 6000 RTP/AVP 0 a=rtpmap:0 PCMU/8000 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: INVITE sip:s@192.168.101.247:5060 SIP/2.0 (41) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: sipp ;tag=10270SIPpTag001 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: sut (36) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 1-10270@127.0.0.1 (26) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 1 INVITE (14) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: sip:sipp@127.0.0.1:5060 (32) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Max-Forwards: 70 (16) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: Subject: Performance Test (25) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 9: Content-Type: application/sdp (29) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 10: Content-Length: 129 (20) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 11: (0) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: v=0 (3) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: o=user1 53655765 2353687637 IN IP4 127.0.0.1 (44) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: s=- (3) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: c=IN IP4 127.0.0.1 (18) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: t=0 0 (5) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: m=audio 6000 RTP/AVP 0 (22) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Nov 30 20:52:05] --- (11 headers 7 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3168 sip_alloc: Allocating new SIP dialog for 1-10270@127.0.0.1 - INVITE (With RTP) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11225 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Nov 30 20:52:05] Using INVITE request as basis request - 1-10270@127.0.0.1 [Nov 30 20:52:05] Sending to 127.0.0.1 : 5060 (non-NAT) [Nov 30 20:52:05] Found peer 'sippuas' [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:7302 check_user_full: Setting NAT on RTP to 524288 [Nov 30 20:52:05] Found RTP audio format 0 [Nov 30 20:52:05] Peer audio RTP is at port 127.0.0.1:6000 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3631 process_sdp: Peer audio RTP is at port 127.0.0.1:6000 [Nov 30 20:52:05] Found description format PCMU [Nov 30 20:52:05] Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Nov 30 20:52:05] Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:10571 handle_request_invite: Checking SIP call limits for device sippuas [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:2217 update_call_counter: Updating call counter for incoming call [Nov 30 20:52:05] Looking for s in test (domain 192.168.101.247) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:6194 build_route: build_route: Contact hop: sip:sipp@127.0.0.1:5060 [Nov 30 20:52:05] list_route: hop: [Nov 30 20:52:05] Transmitting (NAT) to 192.168.101.9:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1677 pbx_extension_helper: Launching 'Dial' [Nov 30 20:52:05] -- Executing Dial("SIP/sippuas-08191938", "SIP/sippuas|20") in new stack [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3168 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:1885 create_addr_from_peer: Setting NAT on RTP to 524288 [Nov 30 20:52:05] DEBUG[2235]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-test-s-1. [Nov 30 20:52:05] DEBUG[2235]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Nov 30 20:52:05] DEBUG[2235]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Nov 30 20:52:05] DEBUG[2235]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPURI. [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2079 sip_call: Outgoing Call for sippuas [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/sippuas-08196e78' [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2217 update_call_counter: Updating call counter for outgoing call [Nov 30 20:52:05] We're at 192.168.101.247 port 10116 [Nov 30 20:52:05] Adding codec 0x4 (ulaw) to SDP [Nov 30 20:52:05] Adding codec 0x2 (gsm) to SDP [Nov 30 20:52:05] Adding codec 0x8 (alaw) to SDP [Nov 30 20:52:05] Adding non-codec 0x1 (telephone-event) to SDP [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 0: INVITE sip:sippuas@192.168.101.9:5061 SIP/2.0 (45) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport (66) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 2: From: "sipp" ;tag=as103bf24b (54) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 3: To: (36) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 4: Contact: (35) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 5: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 (57) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 6: CSeq: 102 INVITE (16) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 8: Max-Forwards: 70 (16) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 9: Date: Thu, 30 Nov 2006 23:52:05 GMT (35) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 11: Content-Type: application/sdp (29) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 12: Content-Length: 267 (19) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3392 parse_request: Header 13: (0) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: v=0 (3) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: o=root 2215 2215 IN IP4 192.168.101.247 (39) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: s=session (9) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: c=IN IP4 192.168.101.247 (24) [Nov 30 20:52:05] DEBUG[2220]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/sippuas-08196e78' [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: t=0 0 (5) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: m=audio 10116 RTP/AVP 0 3 8 101 (31) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=rtpmap:3 GSM/8000 (19) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=fmtp:101 0-16 (15) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:3424 parse_request: Line: a=silenceSupp:off - - - - (25) [Nov 30 20:52:05] 13 headers, 12 lines [Nov 30 20:52:05] DEBUG[2220]: channel.c:777 channel_find_locked: Avoiding initial deadlock for 'SIP/sippuas-08196e78' [Nov 30 20:52:05] Reliably Transmitting (NAT) to 192.168.101.9:5061: INVITE sip:sippuas@192.168.101.9:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport From: "sipp" ;tag=as103bf24b To: Contact: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Thu, 30 Nov 2006 23:52:05 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 267 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10116 RTP/AVP 0 3 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:1304 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #1 [Nov 30 20:52:05] -- Called sippuas [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5061: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport From: "sipp" ;tag=as103bf24b To: ;tag=9572SIPpTag0118 Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 102 INVITE Contact: Content-Length: 0 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: SIP/2.0 180 Ringing (19) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport (66) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: "sipp" ;tag=as103bf24b (54) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: ;tag=9572SIPpTag0118 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 (57) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 102 INVITE (16) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: (43) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Content-Length: 0 (17) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: (0) [Nov 30 20:52:05] --- (8 headers 0 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 Their Tag Our tag: as103bf24b [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1456 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #1 - INVITE (got response) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1465 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '38050e8913cf715d0977f8f74749dec4@192.168.101.247' Request 102: Found [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:9642 handle_response_invite: SIP response 180 to standard invite [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport From: "sipp" ;tag=as103bf24b To: ;tag=9572SIPpTag0118 Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 102 INVITE Contact: Content-Type: application/sdp Content-Length: 129 v=0 o=user1 53655765 2353687637 IN IP4 127.0.0.1 s=- c=IN IP4 127.0.0.1 t=0 0 m=audio 6001 RTP/AVP 0 a=rtpmap:0 PCMU/8000 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: SIP/2.0 200 OK (14) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK3434bac9;rport (66) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: "sipp" ;tag=as103bf24b (54) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: ;tag=9572SIPpTag0118 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 (57) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 102 INVITE (16) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: (43) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Content-Type: application/sdp (29) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: Content-Length: 129 (20) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 9: (0) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: v=0 (3) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: o=user1 53655765 2353687637 IN IP4 127.0.0.1 (44) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: s=- (3) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: c=IN IP4 127.0.0.1 (18) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: t=0 0 (5) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: m=audio 6001 RTP/AVP 0 (22) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3424 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Nov 30 20:52:05] --- (9 headers 7 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 Their Tag 9572SIPpTag0118 Our tag: as103bf24b [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1390 __sip_ack: Acked pending invite 102 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1412 __sip_ack: Stopping retransmission on '38050e8913cf715d0977f8f74749dec4@192.168.101.247' of Request 102: Match Found [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:9642 handle_response_invite: SIP response 200 to standard invite [Nov 30 20:52:05] Found RTP audio format 0 [Nov 30 20:52:05] Peer audio RTP is at port 127.0.0.1:6001 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3631 process_sdp: Peer audio RTP is at port 127.0.0.1:6001 [Nov 30 20:52:05] Found description format PCMU [Nov 30 20:52:05] Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) [Nov 30 20:52:05] Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:6194 build_route: build_route: Contact hop: [Nov 30 20:52:05] list_route: hop: [Nov 30 20:52:05] set_destination: Parsing for address/port to send to [Nov 30 20:52:05] set_destination: set destination to 127.0.0.1, port 5061 [Nov 30 20:52:05] Transmitting (NAT) to 192.168.101.9:5061: ACK sip:127.0.0.1:5061;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK427a37ae;rport From: "sipp" ;tag=as103bf24b To: ;tag=9572SIPpTag0118 Contact: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 6 (Ringing) [Nov 30 20:52:05] DEBUG[2236]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 6 (Ringing) [Nov 30 20:52:05] DEBUG[2237]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Nov 30 20:52:05] -- SIP/sippuas-08196e78 is ringing [Nov 30 20:52:05] Transmitting (NAT) to 192.168.101.9:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- [Nov 30 20:52:05] -- SIP/sippuas-08196e78 answered SIP/sippuas-08191938 [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2557 sip_answer: sip_answer(SIP/sippuas-08191938) [Nov 30 20:52:05] We're at 192.168.101.247 port 10676 [Nov 30 20:52:05] Adding codec 0x4 (ulaw) to SDP [Nov 30 20:52:05] Reliably Transmitting (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:1304 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #2 [Nov 30 20:52:05] -- Attempting native bridge of SIP/sippuas-08191938 and SIP/sippuas-08196e78 [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 2 (In use) [Nov 30 20:52:05] DEBUG[2238]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5060: BYE sip:s@192.168.101.247:5060 SIP/2.0 Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-5 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 2 BYE Contact: sip:sipp@127.0.0.1:5060 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: BYE sip:s@192.168.101.247:5060 SIP/2.0 (38) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-5 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: sipp ;tag=10270SIPpTag001 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: sut ;tag=as6a7e1777 (51) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 1-10270@127.0.0.1 (26) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 2 BYE (11) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: sip:sipp@127.0.0.1:5060 (32) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Max-Forwards: 70 (16) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: Subject: Performance Test (25) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 9: Content-Length: 0 (17) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 10: (0) [Nov 30 20:52:05] --- (10 headers 0 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = No match Their Call ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 Their Tag 9572SIPpTag0118 Our tag: as103bf24b [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 1-10270@127.0.0.1 Their Tag 10270SIPpTag001 Our tag: as6a7e1777 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11225 handle_request: **** Received BYE (8) - Command in SIP BYE [Nov 30 20:52:05] Reliably Transmitting (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1304 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #3 [Nov 30 20:52:05] Sending to 127.0.0.1 : 5060 (NAT) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:10876 handle_request_bye: Received bye, issuing owner hangup . [Nov 30 20:52:05] Transmitting (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-5;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 2 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:05] DEBUG[2235]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: SIP/sippuas-08191938 [Nov 30 20:52:05] DEBUG[2235]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels SIP/sippuas-08191938 and SIP/sippuas-08196e78 [Nov 30 20:52:05] DEBUG[2235]: channel.c:1373 ast_hangup: Hanging up channel 'SIP/sippuas-08196e78' [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2427 sip_hangup: Hangup call SIP/sippuas-08196e78, SIP callid 38050e8913cf715d0977f8f74749dec4@192.168.101.247) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2435 sip_hangup: update_call_counter(sippuas) - decrement call limit counter [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2217 update_call_counter: Updating call counter for outgoing call [Nov 30 20:52:05] Scheduling destruction of call '38050e8913cf715d0977f8f74749dec4@192.168.101.247' in 32000 ms [Nov 30 20:52:05] set_destination: Parsing for address/port to send to [Nov 30 20:52:05] set_destination: set destination to 127.0.0.1, port 5061 [Nov 30 20:52:05] Reliably Transmitting (NAT) to 192.168.101.9:5061: BYE sip:127.0.0.1:5061;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK56db73cd;rport From: "sipp" ;tag=as103bf24b To: ;tag=9572SIPpTag0118 Contact: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 103 BYE User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:1304 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #5 [Nov 30 20:52:05] DEBUG[2235]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Nov 30 20:52:05] DEBUG[2235]: pbx.c:2316 __ast_pbx_run: Spawn extension (test,s,1) exited non-zero on 'SIP/sippuas-08191938' [Nov 30 20:52:05] == Spawn extension (test, s, 1) exited non-zero on 'SIP/sippuas-08191938' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK56db73cd;rport From: "sipp" ;tag=as103bf24b To: ;tag=9572SIPpTag0118 Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 CSeq: 103 BYE Contact: Content-Length: 0 Function result is '"sipp" ' [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: SIP/2.0 200 OK (14) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.101.247:5060;branch=z9hG4bK56db73cd;rport (66) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: "sipp" ;tag=as103bf24b (54) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: ;tag=9572SIPpTag0118 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 (57) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 103 BYE (13) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: (43) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Content-Length: 0 (17) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: (0) [Nov 30 20:52:05] --- (8 headers 0 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 38050e8913cf715d0977f8f74749dec4@192.168.101.247 Their Tag 9572SIPpTag0118 Our tag: as103bf24b [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1401 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #5 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:1412 __sip_ack: Stopping retransmission on '38050e8913cf715d0977f8f74749dec4@192.168.101.247' of Request 103: Match Found [Nov 30 20:52:05] Destroying call '38050e8913cf715d0977f8f74749dec4@192.168.101.247' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'sipp' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 's' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'test' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/sippuas-08191938' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/sippuas-08196e78' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'Dial' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/sippuas|20' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 20:52:05' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 20:52:05' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 20:52:05' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '1164930725.0' [Nov 30 20:52:05] DEBUG[2235]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)' [Nov 30 20:52:05] <-- SIP read from 192.168.101.9:5060: ACK sip:s@192.168.101.247:5060 SIP/2.0 Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-7 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 ACK Contact: sip:sipp@127.0.0.1:5060 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 0: ACK sip:s@192.168.101.247:5060 SIP/2.0 (38) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 1: Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-7 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 2: From: sipp ;tag=10270SIPpTag001 (56) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 3: To: sut ;tag=as6a7e1777 (51) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 4: Call-ID: 1-10270@127.0.0.1 (26) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 5: CSeq: 1 ACK (11) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 6: Contact: sip:sipp@127.0.0.1:5060 (32) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 7: Max-Forwards: 70 (16) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 8: Subject: Performance Test (25) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 9: Content-Length: 0 (17) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3392 parse_request: Header 10: (0) [Nov 30 20:52:05] --- (10 headers 0 lines) --- [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 1-10270@127.0.0.1 Their Tag 10270SIPpTag001 Our tag: as6a7e1777 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11399 sipsock_read: Failed to grab lock, trying again... [Nov 30 20:52:05] DEBUG[2235]: channel.c:1373 ast_hangup: Hanging up channel 'SIP/sippuas-08191938' [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2427 sip_hangup: Hangup call SIP/sippuas-08191938, SIP callid 1-10270@127.0.0.1) [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2435 sip_hangup: update_call_counter(sippuas) - decrement call limit counter [Nov 30 20:52:05] DEBUG[2235]: chan_sip.c:2217 update_call_counter: Updating call counter for incoming call [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:3216 find_call: = Found Their Call ID: 1-10270@127.0.0.1 Their Tag 10270SIPpTag001 Our tag: as6a7e1777 [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11225 handle_request: **** Received ACK (6) - Command in SIP ACK [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11229 handle_request: Ignoring too old SIP packet packet 1 (expecting >= 2) [Nov 30 20:52:05] DEBUG[2227]: chan_sip.c:11423 sipsock_read: SIP message could not be handled, bad request: 1-10270@127.0.0.1 [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 1 (Not in use) [Nov 30 20:52:05] DEBUG[2239]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 1 (Not in use) [Nov 30 20:52:05] DEBUG[2240]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 30 20:52:05] DEBUG[2220]: chan_sip.c:11767 sip_devicestate: Checking device state for peer sippuas [Nov 30 20:52:05] DEBUG[2220]: devicestate.c:187 do_state_change: Changing state for SIP/sippuas - state 1 (Not in use) [Nov 30 20:52:05] DEBUG[2241]: app_queue.c:500 changethread: Device 'SIP/sippuas' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 30 20:52:06] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (1) SIP/2.0 - 1 [Nov 30 20:52:06] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:06] Retransmitting #1 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:06] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (1) SIP/2.0 - 1 [Nov 30 20:52:06] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:06] Retransmitting #1 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:07] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (2) SIP/2.0 - 1 [Nov 30 20:52:07] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:07] Retransmitting #2 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:07] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (2) SIP/2.0 - 1 [Nov 30 20:52:07] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:07] Retransmitting #2 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:09] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (3) SIP/2.0 - 1 [Nov 30 20:52:09] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:09] Retransmitting #3 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:09] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (3) SIP/2.0 - 1 [Nov 30 20:52:09] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:09] Retransmitting #3 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:13] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (4) SIP/2.0 - 1 [Nov 30 20:52:13] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:13] Retransmitting #4 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:13] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (4) SIP/2.0 - 1 [Nov 30 20:52:13] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:13] Retransmitting #4 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:17] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (5) SIP/2.0 - 1 [Nov 30 20:52:17] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:17] Retransmitting #5 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:17] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (5) SIP/2.0 - 1 [Nov 30 20:52:17] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:17] Retransmitting #5 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:21] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #2 (6) SIP/2.0 - 1 [Nov 30 20:52:21] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #2)) [Nov 30 20:52:21] Retransmitting #6 (NAT) to 192.168.101.9:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 2215 2215 IN IP4 192.168.101.247 s=session c=IN IP4 192.168.101.247 t=0 0 m=audio 10676 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - --- [Nov 30 20:52:21] DEBUG[2227]: chan_sip.c:1195 retrans_pkt: SIP TIMER: Rescheduling retransmission #3 (6) SIP/2.0 - 1 [Nov 30 20:52:21] DEBUG[2227]: chan_sip.c:1209 retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #3)) [Nov 30 20:52:21] Retransmitting #6 (NAT) to 192.168.101.9:5060: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-10270-1-0;received=192.168.101.9 From: sipp ;tag=10270SIPpTag001 To: sut ;tag=as6a7e1777 Call-ID: 1-10270@127.0.0.1 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- [Nov 30 20:52:25] WARNING[2227]: chan_sip.c:1228 retrans_pkt: Maximum retries exceeded on transmission 1-10270@127.0.0.1 for seqno 1 (Non-critical Response) [Nov 30 20:52:25] WARNING[2227]: chan_sip.c:1228 retrans_pkt: Maximum retries exceeded on transmission 1-10270@127.0.0.1 for seqno 1 (Critical Response) [Nov 30 20:52:25] Destroying call '1-10270@127.0.0.1' *CLI> stop now  [Nov 30 20:52:33] Beginning asterisk shutdown.... [Nov 30 20:52:33] Executing last minute cleanups [Nov 30 20:52:33] == Destroying musiconhold processes [Nov 30 20:52:33] Asterisk cleanly ending (0). [Nov 30 20:52:33] DEBUG[2215]: asterisk.c:900 quit_handler: Asterisk ending (0).