Parsing /etc/asterisk/asterisk.conf Parsing /etc/asterisk/extconfig.conf [Mar 19 22:57:58] == Parsing '/etc/asterisk/asterisk.conf': [Mar 19 22:57:58] Found [Mar 19 22:57:58] == Parsing '/etc/asterisk/extconfig.conf': [Mar 19 22:57:58] Found [Mar 19 22:57:58] Asterisk 1.2.16, Copyright (C) 1999 - 2006 Digium, Inc. and others. [Mar 19 22:57:58] Created by Mark Spencer [Mar 19 22:57:58] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'show warranty' for details. [Mar 19 22:57:58] This is free software, with components licensed under the GNU General Public [Mar 19 22:57:58] License version 2 and other licenses; you are welcome to redistribute it under [Mar 19 22:57:58] certain conditions. Type 'show license' for details. [Mar 19 22:57:58] ========================================================================= [Mar 19 22:57:59] == Parsing '/etc/asterisk/logger.conf': Parsing /etc/asterisk/logger.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] Asterisk Event Logger Started /var/log/asterisk/event_log [Mar 19 22:57:59] == Parsing '/etc/asterisk/dnsmgr.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/dnsmgr.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] Asterisk Dynamic Loader loading preload modules: [Mar 19 22:57:59] == Parsing '/etc/asterisk/modules.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/modules.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] == Manager registered action Ping [Mar 19 22:57:59] == Manager registered action Events [Mar 19 22:57:59] == Manager registered action Logoff [Mar 19 22:57:59] == Manager registered action Hangup [Mar 19 22:57:59] == Manager registered action Status [Mar 19 22:57:59] == Manager registered action Setvar [Mar 19 22:57:59] == Manager registered action Getvar [Mar 19 22:57:59] == Manager registered action Redirect [Mar 19 22:57:59] == Manager registered action Originate [Mar 19 22:57:59] == Manager registered action Command [Mar 19 22:57:59] == Manager registered action ExtensionState [Mar 19 22:57:59] == Manager registered action AbsoluteTimeout [Mar 19 22:57:59] == Manager registered action MailboxStatus [Mar 19 22:57:59] == Manager registered action MailboxCount [Mar 19 22:57:59] == Manager registered action ListCommands [Mar 19 22:57:59] == Parsing '/etc/asterisk/manager.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/manager.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] == Parsing '/etc/asterisk/cdr.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] NOTICE[3942]: cdr.c:1193 do_reload: CDR simple logging enabled. [Mar 19 22:57:59] == Parsing '/etc/asterisk/rtp.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/rtp.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] == RTP Allocating from port range 10000 -> 20000 [Mar 19 22:57:59] Asterisk PBX Core Initializing [Mar 19 22:57:59] Registering builtin applications: [Mar 19 22:57:59] [AbsoluteTimeout] [Mar 19 22:57:59] == Registered application 'AbsoluteTimeout' [Mar 19 22:57:59] [Answer] [Mar 19 22:57:59] == Registered application 'Answer' [Mar 19 22:57:59] [BackGround] [Mar 19 22:57:59] == Registered application 'BackGround' [Mar 19 22:57:59] [Busy] [Mar 19 22:57:59] == Registered application 'Busy' [Mar 19 22:57:59] [Congestion] [Mar 19 22:57:59] == Registered application 'Congestion' [Mar 19 22:57:59] [DigitTimeout] [Mar 19 22:57:59] == Registered application 'DigitTimeout' [Mar 19 22:57:59] [Goto] [Mar 19 22:57:59] == Registered application 'Goto' [Mar 19 22:57:59] [GotoIf] [Mar 19 22:57:59] == Registered application 'GotoIf' [Mar 19 22:57:59] [GotoIfTime] [Mar 19 22:57:59] == Registered application 'GotoIfTime' [Mar 19 22:57:59] [ExecIfTime] [Mar 19 22:57:59] == Registered application 'ExecIfTime' [Mar 19 22:57:59] [Hangup] [Mar 19 22:57:59] == Registered application 'Hangup' [Mar 19 22:57:59] [NoOp] [Mar 19 22:57:59] == Registered application 'NoOp' [Mar 19 22:57:59] [Progress] [Mar 19 22:57:59] == Registered application 'Progress' [Mar 19 22:57:59] [ResetCDR] [Mar 19 22:57:59] == Registered application 'ResetCDR' [Mar 19 22:57:59] [ResponseTimeout] [Mar 19 22:57:59] == Registered application 'ResponseTimeout' [Mar 19 22:57:59] [Ringing] [Mar 19 22:57:59] == Registered application 'Ringing' [Mar 19 22:57:59] [SayNumber] [Mar 19 22:57:59] == Registered application 'SayNumber' [Mar 19 22:57:59] [SayDigits] [Mar 19 22:57:59] == Registered application 'SayDigits' [Mar 19 22:57:59] [SayAlpha] [Mar 19 22:57:59] == Registered application 'SayAlpha' [Mar 19 22:57:59] [SayPhonetic] [Mar 19 22:57:59] == Registered application 'SayPhonetic' [Mar 19 22:57:59] [SetAccount] [Mar 19 22:57:59] == Registered application 'SetAccount' [Mar 19 22:57:59] [SetAMAFlags] [Mar 19 22:57:59] == Registered application 'SetAMAFlags' [Mar 19 22:57:59] [SetGlobalVar] [Mar 19 22:57:59] == Registered application 'SetGlobalVar' [Mar 19 22:57:59] [SetLanguage] [Mar 19 22:57:59] == Registered application 'SetLanguage' [Mar 19 22:57:59] [Set] [Mar 19 22:57:59] == Registered application 'Set' [Mar 19 22:57:59] [SetVar] [Mar 19 22:57:59] == Registered application 'SetVar' [Mar 19 22:57:59] [ImportVar] [Mar 19 22:57:59] == Registered application 'ImportVar' [Mar 19 22:57:59] [Wait] [Mar 19 22:57:59] == Registered application 'Wait' [Mar 19 22:57:59] [WaitExten] [Mar 19 22:57:59] == Registered application 'WaitExten' [Mar 19 22:57:59] == Manager registered action DBGet [Mar 19 22:57:59] == Manager registered action DBPut [Mar 19 22:57:59] == Parsing '/etc/asterisk/enum.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/enum.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] Asterisk Dynamic Loader Starting: [Mar 19 22:57:59] == Parsing '/etc/asterisk/modules.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/modules.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] [res_musiconhold.so][Mar 19 22:57:59] => (Music On Hold Resource) [Mar 19 22:57:59] == Registered application 'MusicOnHold' [Mar 19 22:57:59] == Registered application 'WaitMusicOnHold' [Mar 19 22:57:59] == Registered application 'SetMusicOnHold' [Mar 19 22:57:59] == Registered application 'StartMusicOnHold' [Mar 19 22:57:59] == Registered application 'StopMusicOnHold' [Mar 19 22:57:59] == Parsing '/etc/asterisk/musiconhold.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/musiconhold.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] [res_indications.so][Mar 19 22:57:59] => (Indications Configuration) [Mar 19 22:57:59] == Parsing '/etc/asterisk/indications.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/indications.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] -- Registered indication country 'at' [Mar 19 22:57:59] -- Registered indication country 'au' [Mar 19 22:57:59] -- Registered indication country 'br' [Mar 19 22:57:59] -- Registered indication country 'be' [Mar 19 22:57:59] -- Registered indication country 'ch' [Mar 19 22:57:59] -- Registered indication country 'cl' [Mar 19 22:57:59] -- Registered indication country 'cn' [Mar 19 22:57:59] -- Registered indication country 'cz' [Mar 19 22:57:59] -- Registered indication country 'de' [Mar 19 22:57:59] -- Registered indication country 'dk' [Mar 19 22:57:59] -- Registered indication country 'ee' [Mar 19 22:57:59] -- Registered indication country 'es' [Mar 19 22:57:59] -- Registered indication country 'fi' [Mar 19 22:57:59] -- Registered indication country 'fr' [Mar 19 22:57:59] -- Registered indication country 'gr' [Mar 19 22:57:59] -- Registered indication country 'hu' [Mar 19 22:57:59] -- Registered indication country 'it' [Mar 19 22:57:59] -- Registered indication country 'lt' [Mar 19 22:57:59] -- Registered indication country 'mx' [Mar 19 22:57:59] -- Registered indication country 'nl' [Mar 19 22:57:59] -- Registered indication country 'no' [Mar 19 22:57:59] -- Registered indication country 'nz' [Mar 19 22:57:59] -- Registered indication country 'pl' [Mar 19 22:57:59] -- Registered indication country 'pt' [Mar 19 22:57:59] -- Registered indication country 'ru' [Mar 19 22:57:59] -- Registered indication country 'se' [Mar 19 22:57:59] -- Registered indication country 'sg' [Mar 19 22:57:59] -- Registered indication country 'uk' [Mar 19 22:57:59] -- Registered indication country 'us' [Mar 19 22:57:59] -- Registered indication country 'us-o' [Mar 19 22:57:59] -- Registered indication country 'tw' [Mar 19 22:57:59] -- Registered indication country 've' [Mar 19 22:57:59] -- Registered indication country 'za' [Mar 19 22:57:59] -- Setting default indication country to 'it' [Mar 19 22:57:59] == Registered application 'PlayTones' [Mar 19 22:57:59] == Registered application 'StopPlayTones' [Mar 19 22:57:59] [res_monitor.so][Mar 19 22:57:59] => (Call Monitoring Resource) [Mar 19 22:57:59] == Registered application 'Monitor' [Mar 19 22:57:59] == Registered application 'StopMonitor' [Mar 19 22:57:59] == Registered application 'ChangeMonitor' [Mar 19 22:57:59] == Manager registered action Monitor [Mar 19 22:57:59] == Manager registered action StopMonitor [Mar 19 22:57:59] == Manager registered action ChangeMonitor [Mar 19 22:57:59] [res_adsi.so][Mar 19 22:57:59] => (ADSI Resource) [Mar 19 22:57:59] == Parsing '/etc/asterisk/adsi.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/adsi.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] [res_agi.so][Mar 19 22:57:59] => (Asterisk Gateway Interface (AGI)) [Mar 19 22:57:59] == Registered application 'DeadAGI' [Mar 19 22:57:59] == Registered application 'EAGI' [Mar 19 22:57:59] == Registered application 'AGI' [Mar 19 22:57:59] [res_features.so][Mar 19 22:57:59] => (Call Features Resource) [Mar 19 22:57:59] == Parsing '/etc/asterisk/features.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/features.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] DEBUG[3942]: pbx.c:3696 ast_context_create: Registered context 'parkedcalls' [Mar 19 22:57:59] -- Registered extension context 'parkedcalls' [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension '700' priority 1 to parkedcalls [Mar 19 22:57:59] -- Added extension '700' priority 1 to parkedcalls [Mar 19 22:57:59] == Registered application 'ParkedCall' [Mar 19 22:57:59] == Registered application 'Park' [Mar 19 22:57:59] == Manager registered action ParkedCalls [Mar 19 22:57:59] [res_crypto.so][Mar 19 22:57:59] => (Cryptographic Digital Signatures) [Mar 19 22:57:59] -- Loaded PUBLIC key 'iaxtel' [Mar 19 22:57:59] DEBUG[3942]: res_crypto.c:257 try_load_key: Key 'iaxtel' loaded OK [Mar 19 22:57:59] -- Loaded PUBLIC key 'freeworlddialup' [Mar 19 22:57:59] DEBUG[3942]: res_crypto.c:257 try_load_key: Key 'freeworlddialup' loaded OK [Mar 19 22:57:59] [pbx_config.so][Mar 19 22:57:59] => (Text Extension Configuration) [Mar 19 22:57:59] == Parsing '/etc/asterisk/extensions.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/extensions.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] DEBUG[3942]: pbx.c:3696 ast_context_create: Registered context 'default' [Mar 19 22:57:59] -- Registered extension context 'default' [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension '_X.' priority 1 to default [Mar 19 22:57:59] -- Added extension '_X.' priority 1 to default [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '_X.' priority 2 to default [Mar 19 22:57:59] -- Added extension '_X.' priority 2 to default [Mar 19 22:57:59] DEBUG[3942]: pbx.c:3696 ast_context_create: Registered context 'eutelia-out' [Mar 19 22:57:59] -- Registered extension context 'eutelia-out' [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension '_0.' priority 1 to eutelia-out [Mar 19 22:57:59] -- Added extension '_0.' priority 1 to eutelia-out [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '_0.' priority 2 to eutelia-out [Mar 19 22:57:59] -- Added extension '_0.' priority 2 to eutelia-out [Mar 19 22:57:59] [pbx_spool.so][Mar 19 22:57:59] => (Outgoing Spool Support) [Mar 19 22:57:59] [pbx_dundi.so][Mar 19 22:57:59] => (Distributed Universal Number Discovery (DUNDi)) [Mar 19 22:57:59] == Parsing '/etc/asterisk/dundi.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/dundi.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] DEBUG[3942]: pbx_dundi.c:422 reset_global_eid: Seeding global EID '00:0a:e4:44:b7:4d' from 'eth0' [Mar 19 22:57:59] == Using TOS bits 0 [Mar 19 22:57:59] == DUNDi Ready and Listening on 0.0.0.0 port 4520 [Mar 19 22:57:59] == Registered application 'DUNDiLookup' [Mar 19 22:57:59] == Registered custom function DUNDILOOKUP [Mar 19 22:57:59] [pbx_loopback.so][Mar 19 22:57:59] => (Loopback Switch) [Mar 19 22:57:59] [pbx_realtime.so][Mar 19 22:57:59] => (Realtime Switch) [Mar 19 22:57:59] [pbx_ael.so][Mar 19 22:57:59] => (Asterisk Extension Language Compiler) [Mar 19 22:57:59] DEBUG[3942]: pbx.c:3696 ast_context_create: Registered context 'macro-std-exten-ael' [Mar 19 22:57:59] -- Registered extension context 'macro-std-exten-ael' [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension 's' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 's' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 's' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 3 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 's' priority 3 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 4 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 's' priority 4 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 5 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 's' priority 5 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension '_sw-4-.' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension 'a' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 'a' priority 1 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 'a' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] -- Added extension 'a' priority 2 to macro-std-exten-ael [Mar 19 22:57:59] DEBUG[3942]: pbx.c:3696 ast_context_create: Registered context 'ael-demo' [Mar 19 22:57:59] -- Registered extension context 'ael-demo' [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension 's' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 3 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 3 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 4 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 4 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 5 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 5 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 6 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 6 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 8 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 8 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 9 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 9 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 10 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 10 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 11 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 11 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension 's' priority 12 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 12 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4850 ast_add_extension2: Added extension 's' priority 7 to ael-demo [Mar 19 22:57:59] -- Added extension 's' priority 7 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension '2' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '2' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '2' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension '2' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension '3' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '3' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '3' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension '3' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension '500' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '500' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '500' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension '500' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '500' priority 3 to ael-demo [Mar 19 22:57:59] -- Added extension '500' priority 3 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '500' priority 4 to ael-demo [Mar 19 22:57:59] -- Added extension '500' priority 4 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension '600' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '600' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '600' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension '600' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '600' priority 3 to ael-demo [Mar 19 22:57:59] -- Added extension '600' priority 3 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '600' priority 4 to ael-demo [Mar 19 22:57:59] -- Added extension '600' priority 4 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4898 ast_add_extension2: Added extension '_1234' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '_1234' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension '#' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension '#' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4864 ast_add_extension2: Added extension '#' priority 2 to ael-demo [Mar 19 22:57:59] -- Added extension '#' priority 2 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension 't' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension 't' priority 1 to ael-demo [Mar 19 22:57:59] DEBUG[3942]: pbx.c:4883 ast_add_extension2: Added extension 'i' priority 1 to ael-demo [Mar 19 22:57:59] -- Added extension 'i' priority 1 to ael-demo [Mar 19 22:57:59] [pbx_functions.so][Mar 19 22:57:59] => (Builtin dialplan functions) [Mar 19 22:57:59] == Registered custom function MD5 [Mar 19 22:57:59] == Registered custom function CHECK_MD5 [Mar 19 22:57:59] == Registered custom function MATH [Mar 19 22:57:59] == Registered custom function GROUP_COUNT [Mar 19 22:57:59] == Registered custom function GROUP_MATCH_COUNT [Mar 19 22:57:59] == Registered custom function GROUP [Mar 19 22:57:59] == Registered custom function GROUP_LIST [Mar 19 22:57:59] == Registered custom function FIELDQTY [Mar 19 22:57:59] == Registered custom function REGEX [Mar 19 22:57:59] == Registered custom function LEN [Mar 19 22:57:59] == Registered custom function STRFTIME [Mar 19 22:57:59] == Registered custom function EVAL [Mar 19 22:57:59] == Registered custom function CDR [Mar 19 22:57:59] == Registered custom function ISNULL [Mar 19 22:57:59] == Registered custom function SET [Mar 19 22:57:59] == Registered custom function EXISTS [Mar 19 22:57:59] == Registered custom function IF [Mar 19 22:57:59] == Registered custom function IFTIME [Mar 19 22:57:59] == Registered custom function ENV [Mar 19 22:57:59] == Registered custom function DB [Mar 19 22:57:59] == Registered custom function DB_EXISTS [Mar 19 22:57:59] == Registered custom function TIMEOUT [Mar 19 22:57:59] == Registered custom function LANGUAGE [Mar 19 22:57:59] == Registered custom function MUSICCLASS [Mar 19 22:57:59] [chan_sip.so][Mar 19 22:57:59] => (Session Initiation Protocol (SIP)) [Mar 19 22:57:59] == Parsing '/etc/asterisk/sip.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/sip.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:12736 reload_config: Setting User Agent Name to Asterisk PBX [Mar 19 22:57:59] DEBUG[3942]: acl.c:199 ast_append_ha: 192.168.1.0/255.255.255.0/255.255.255.0 appended to acl for peer [Mar 19 22:57:59] -- parse_srv: SRV mapped to host voip.eutelia.it, port 5060 [Mar 19 22:57:59] -- SIP Seeding peer from astdb: '1000' at 1000@192.168.1.254:5060 for 3600 [Mar 19 22:57:59] -- SIP Seeding peer from astdb: '2000' at 2000@192.168.1.254:5060 for 3600 [Mar 19 22:57:59] -- SIP Seeding peer from astdb: '3000' at 3000@192.168.1.254:5060 for 3600 [Mar 19 22:57:59] -- SIP Seeding peer from astdb: '4000' at 4000@192.168.1.254:5060 for 3600 [Mar 19 22:57:59] == SIP Listening on 0.0.0.0:5060 [Mar 19 22:57:59] == Using TOS bits 0 [Mar 19 22:57:59] == Parsing '/etc/asterisk/sip_notify.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) [Mar 19 22:57:59] == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) [Mar 19 22:57:59] == Registered application 'SIPDtmfMode' [Mar 19 22:57:59] == Registered application 'SIPAddHeader' [Mar 19 22:57:59] == Registered application 'SIPGetHeader' [Mar 19 22:57:59] == Registered custom function SIP_HEADER [Mar 19 22:57:59] == Registered custom function SIPPEER [Mar 19 22:57:59] == Registered custom function SIPCHANINFO [Mar 19 22:57:59] == Registered custom function CHECKSIPDOMAIN [Mar 19 22:57:59] == Manager registered action SIPpeers [Mar 19 22:57:59] == Manager registered action SIPshowpeer [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:57:59] DEBUG[3942]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:57:59] DEBUG[3942]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:57:59] DEBUG[3942]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:57:59] DEBUG[3942]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:57:59] DEBUG[3942]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 22:57:59] DEBUG[3942]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 22:57:59] [chan_agent.so][Mar 19 22:57:59] => (Agent Proxy Channel) [Mar 19 22:57:59] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Agent' (Call Agent Proxy Channel) [Mar 19 22:57:59] == Registered channel type 'Agent' (Call Agent Proxy Channel) [Mar 19 22:57:59] == Registered application 'AgentLogin' [Mar 19 22:57:59] == Registered application 'AgentCallbackLogin' [Mar 19 22:57:59] == Registered application 'AgentMonitorOutgoing' [Mar 19 22:57:59] == Manager registered action Agents [Mar 19 22:57:59] == Manager registered action AgentLogoff [Mar 19 22:57:59] == Manager registered action AgentCallbackLogin [Mar 19 22:57:59] == Parsing '/etc/asterisk/agents.conf': [Mar 19 22:57:59] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/agents.conf [Mar 19 22:57:59] Found [Mar 19 22:57:59] [chan_mgcp.so][Mar 19 22:58:00] => (Media Gateway Control Protocol (MGCP)) [Mar 19 22:58:00] == Parsing '/etc/asterisk/mgcp.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/mgcp.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] == MGCP Listening on 0.0.0.0:2727 [Mar 19 22:58:00] == Using TOS bits 0 [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) [Mar 19 22:58:00] == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) [Mar 19 22:58:00] [chan_iax2.so][Mar 19 22:58:00] => (Inter Asterisk eXchange (Ver 2)) [Mar 19 22:58:00] == Registered custom function IAXPEER [Mar 19 22:58:00] == Registered application 'IAX2Provision' [Mar 19 22:58:00] == Manager registered action IAXpeers [Mar 19 22:58:00] == Manager registered action IAXnetstats [Mar 19 22:58:00] == Parsing '/etc/asterisk/iax.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/iax.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] == Using TOS bits 16 [Mar 19 22:58:00] == Binding IAX2 to default address 0.0.0.0:4569 [Mar 19 22:58:00] -- doing lookup for '216.207.245.47' [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Mar 19 22:58:00] == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) [Mar 19 22:58:00] == IAX Ready and Listening [Mar 19 22:58:00] == Loaded firmware 'iaxy.bin' [Mar 19 22:58:00] == Parsing '/etc/asterisk/iaxprov.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/iaxprov.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for 4c2de941586fa468124537f0533b8da0@192.168.1.15 - REGISTER (No RTP) [Mar 19 22:58:00] -- Loaded provisioning template 'default' [Mar 19 22:58:00] [chan_local.so][Mar 19 22:58:00] => (Local Proxy Channel) [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Local' (Local Proxy Channel Driver) [Mar 19 22:58:00] == Registered channel type 'Local' (Local Proxy Channel Driver) [Mar 19 22:58:00] [chan_skinny.so][Mar 19 22:58:00] => (Skinny Client Control Protocol (Skinny)) [Mar 19 22:58:00] == Parsing '/etc/asterisk/skinny.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/skinny.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] == Skinny listening on 0.0.0.0:2000 [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) [Mar 19 22:58:00] == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) [Mar 19 22:58:00] -- parse_srv: SRV mapped to host voip.eutelia.it, port 5060 [Mar 19 22:58:00] [chan_features.so][Mar 19 22:58:00] => (Feature Proxy Channel) [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Feature' (Feature Proxy Channel Driver) [Mar 19 22:58:00] == Registered channel type 'Feature' (Feature Proxy Channel Driver) [Mar 19 22:58:00] [chan_oss.so][Mar 19 22:58:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:5625 transmit_register: Scheduled a registration timeout for voip.eutelia.it id #20 [Mar 19 22:58:00] REGISTER attempt 1 to 09611915350@voip.eutelia.it [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 0572d1472d6319871e707f4f5f00c250@62.94.37.175 Their Tag Our tag: as7a8dc5e6 [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '0572d1472d6319871e707f4f5f00c250@62.94.37.175' of Request 102: Match Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 47d191a573f33ebb238c6d0072827f7a@192.168.1.15 Their Tag Our tag: as3f5a981d [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 028bf00f762ecc814c054ff1070da45f@192.168.1.15 Their Tag Our tag: as74a667ba [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 0ee0789e76eaee8b26ddc5d623f4db02@192.168.1.15 Their Tag Our tag: as6146bc93 [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 438e8c4145ef10d8221bcd42710a1a5d@192.168.1.15 Their Tag Our tag: as671648ad [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '438e8c4145ef10d8221bcd42710a1a5d@192.168.1.15' of Request 102: Match Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 47d191a573f33ebb238c6d0072827f7a@192.168.1.15 Their Tag Our tag: as3f5a981d [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 028bf00f762ecc814c054ff1070da45f@192.168.1.15 Their Tag Our tag: as74a667ba [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 0ee0789e76eaee8b26ddc5d623f4db02@192.168.1.15 Their Tag Our tag: as6146bc93 [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '0ee0789e76eaee8b26ddc5d623f4db02@192.168.1.15' of Request 102: Match Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 47d191a573f33ebb238c6d0072827f7a@192.168.1.15 Their Tag Our tag: as3f5a981d [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 028bf00f762ecc814c054ff1070da45f@192.168.1.15 Their Tag Our tag: as74a667ba [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '028bf00f762ecc814c054ff1070da45f@192.168.1.15' of Request 102: Match Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = No match Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 47d191a573f33ebb238c6d0072827f7a@192.168.1.15 Their Tag Our tag: as3f5a981d [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '47d191a573f33ebb238c6d0072827f7a@192.168.1.15' of Request 102: Match Found [Mar 19 22:58:00] DEBUG[3946]: chan_sip.c:11844 sip_devicestate: Checking device state for peer eutelia [Mar 19 22:58:00] => (OSS Console Channel Driver) [Mar 19 22:58:00] == Parsing '/etc/asterisk/oss.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/oss.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Console' (OSS Console Channel Driver) [Mar 19 22:58:00] == Registered channel type 'Console' (OSS Console Channel Driver) [Mar 19 22:58:00] [chan_phone.so][Mar 19 22:58:00] DEBUG[3946]: devicestate.c:187 do_state_change: Changing state for SIP/eutelia - state 4 (Invalid) [Mar 19 22:58:00] DEBUG[3946]: chan_sip.c:11844 sip_devicestate: Checking device state for peer 4000 [Mar 19 22:58:00] DEBUG[3946]: devicestate.c:187 do_state_change: Changing state for SIP/4000 - state 1 (Not in use) [Mar 19 22:58:00] DEBUG[3946]: chan_sip.c:11844 sip_devicestate: Checking device state for peer 3000 [Mar 19 22:58:00] DEBUG[3946]: devicestate.c:187 do_state_change: Changing state for SIP/3000 - state 1 (Not in use) [Mar 19 22:58:00] DEBUG[3946]: chan_sip.c:11844 sip_devicestate: Checking device state for peer 2000 [Mar 19 22:58:00] DEBUG[3946]: devicestate.c:187 do_state_change: Changing state for SIP/2000 - state 1 (Not in use) [Mar 19 22:58:00] DEBUG[3946]: chan_sip.c:11844 sip_devicestate: Checking device state for peer 1000 [Mar 19 22:58:00] DEBUG[3946]: devicestate.c:187 do_state_change: Changing state for SIP/1000 - state 1 (Not in use) [Mar 19 22:58:00] => (Linux Telephony API Support) [Mar 19 22:58:00] == Parsing '/etc/asterisk/phone.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/phone.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as3f7a73ca [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '4c2de941586fa468124537f0533b8da0@192.168.1.15' of Request 102: Match Found [Mar 19 22:58:00] REGISTER attempt 2 to 09611915350@voip.eutelia.it [Mar 19 22:58:00] DEBUG[3942]: channel.c:344 ast_channel_register: Registered handler for 'Phone' (Standard Linux Telephony API Driver) [Mar 19 22:58:00] == Registered channel type 'Phone' (Standard Linux Telephony API Driver) [Mar 19 22:58:00] [app_dial.so][Mar 19 22:58:00] => (Dialing Application) [Mar 19 22:58:00] == Registered application 'Dial' [Mar 19 22:58:00] == Registered application 'RetryDial' [Mar 19 22:58:00] [app_playback.so][Mar 19 22:58:00] => (Sound File Playback Application) [Mar 19 22:58:00] == Registered application 'Playback' [Mar 19 22:58:00] [app_voicemail.so][Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:3239 find_call: = Found Their Call ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 Their Tag Our tag: as16fcd275 [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '4c2de941586fa468124537f0533b8da0@192.168.1.15' of Request 103: Match Found [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:9927 handle_response_register: Registration successful [Mar 19 22:58:00] DEBUG[3953]: chan_sip.c:9929 handle_response_register: Cancelling timeout 20 [Mar 19 22:58:00] => (Comedian Mail (Voicemail System)) [Mar 19 22:58:00] == Registered application 'VoiceMail' [Mar 19 22:58:00] == Registered application 'VoiceMailMain' [Mar 19 22:58:00] == Registered application 'MailboxExists' [Mar 19 22:58:00] == Registered application 'VMAuthenticate' [Mar 19 22:58:00] == Parsing '/etc/asterisk/voicemail.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/voicemail.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6116 load_config: VM Review Option disabled globally [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6122 load_config: VM Operator break disabled globally [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6128 load_config: VM CID Info before msg disabled globally [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6140 load_config: ENVELOPE before msg enabled globally [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6146 load_config: Duration info before msg enabled globally [Mar 19 22:58:00] DEBUG[3942]: app_voicemail.c:6161 load_config: We are not going to skip to the next msg after save/delete [Mar 19 22:58:00] [app_directory.so][Mar 19 22:58:00] => (Extension Directory) [Mar 19 22:58:00] == Registered application 'Directory' [Mar 19 22:58:00] [app_mp3.so][Mar 19 22:58:00] => (Silly MP3 Application) [Mar 19 22:58:00] == Registered application 'MP3Player' [Mar 19 22:58:00] [app_system.so][Mar 19 22:58:00] => (Generic System() application) [Mar 19 22:58:00] == Registered application 'TrySystem' [Mar 19 22:58:00] == Registered application 'System' [Mar 19 22:58:00] [app_echo.so][Mar 19 22:58:00] => (Simple Echo Application) [Mar 19 22:58:00] == Registered application 'Echo' [Mar 19 22:58:00] [app_record.so][Mar 19 22:58:00] => (Trivial Record Application) [Mar 19 22:58:00] == Registered application 'Record' [Mar 19 22:58:00] [app_image.so][Mar 19 22:58:00] => (Image Transmission Application) [Mar 19 22:58:00] == Registered application 'SendImage' [Mar 19 22:58:00] [app_url.so][Mar 19 22:58:00] => (Send URL Applications) [Mar 19 22:58:00] == Registered application 'SendURL' [Mar 19 22:58:00] [app_disa.so][Mar 19 22:58:00] => (DISA (Direct Inward System Access) Application) [Mar 19 22:58:00] == Registered application 'DISA' [Mar 19 22:58:00] [app_adsiprog.so][Mar 19 22:58:00] => (Asterisk ADSI Programming Application) [Mar 19 22:58:00] == Registered application 'ADSIProg' [Mar 19 22:58:00] [app_getcpeid.so][Mar 19 22:58:00] => (Get ADSI CPE ID) [Mar 19 22:58:00] == Registered application 'GetCPEID' [Mar 19 22:58:00] [app_milliwatt.so][Mar 19 22:58:00] => (Digital Milliwatt (mu-law) Test Application) [Mar 19 22:58:00] == Registered application 'Milliwatt' [Mar 19 22:58:00] [app_zapateller.so][Mar 19 22:58:00] => (Block Telemarketers with Special Information Tone) [Mar 19 22:58:00] == Registered application 'Zapateller' [Mar 19 22:58:00] [app_setcallerid.so][Mar 19 22:58:00] => (Set CallerID Application) [Mar 19 22:58:00] == Registered application 'SetCallerPres' [Mar 19 22:58:00] == Registered application 'SetCallerID' [Mar 19 22:58:00] [app_festival.so][Mar 19 22:58:00] => (Simple Festival Interface) [Mar 19 22:58:00] == Registered application 'Festival' [Mar 19 22:58:00] [app_queue.so][Mar 19 22:58:00] => (True Call Queueing) [Mar 19 22:58:00] == Registered application 'Queue' [Mar 19 22:58:00] == Manager registered action Queues [Mar 19 22:58:00] == Manager registered action QueueStatus [Mar 19 22:58:00] == Manager registered action QueueAdd [Mar 19 22:58:00] == Manager registered action QueueRemove [Mar 19 22:58:00] == Manager registered action QueuePause [Mar 19 22:58:00] == Registered application 'AddQueueMember' [Mar 19 22:58:00] == Registered application 'RemoveQueueMember' [Mar 19 22:58:00] == Registered application 'PauseQueueMember' [Mar 19 22:58:00] == Registered application 'UnpauseQueueMember' [Mar 19 22:58:00] == Registered custom function QUEUEAGENTCOUNT [Mar 19 22:58:00] == Parsing '/etc/asterisk/queues.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/queues.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] [app_senddtmf.so][Mar 19 22:58:00] => (Send DTMF digits Application) [Mar 19 22:58:00] == Registered application 'SendDTMF' [Mar 19 22:58:00] [app_parkandannounce.so][Mar 19 22:58:00] => (Call Parking and Announce Application) [Mar 19 22:58:00] == Registered application 'ParkAndAnnounce' [Mar 19 22:58:00] [app_setcidname.so][Mar 19 22:58:00] => (Set CallerID Name) [Mar 19 22:58:00] == Registered application 'SetCIDName' [Mar 19 22:58:00] [app_lookupcidname.so][Mar 19 22:58:00] => (Look up CallerID Name from local database) [Mar 19 22:58:00] == Registered application 'LookupCIDName' [Mar 19 22:58:00] [app_macro.so][Mar 19 22:58:00] => (Extension Macros) [Mar 19 22:58:00] == Registered application 'MacroExit' [Mar 19 22:58:00] == Registered application 'MacroIf' [Mar 19 22:58:00] == Registered application 'Macro' [Mar 19 22:58:00] [app_authenticate.so][Mar 19 22:58:00] => (Authentication Application) [Mar 19 22:58:00] == Registered application 'Authenticate' [Mar 19 22:58:00] [app_softhangup.so][Mar 19 22:58:00] => (Hangs up the requested channel) [Mar 19 22:58:00] == Registered application 'SoftHangup' [Mar 19 22:58:00] [app_lookupblacklist.so][Mar 19 22:58:00] => (Look up Caller*ID name/number from blacklist database) [Mar 19 22:58:00] == Registered application 'LookupBlacklist' [Mar 19 22:58:00] [app_waitforring.so][Mar 19 22:58:00] => (Waits until first ring after time) [Mar 19 22:58:00] == Registered application 'WaitForRing' [Mar 19 22:58:00] [app_privacy.so][Mar 19 22:58:00] => (Require phone number to be entered, if no CallerID sent) [Mar 19 22:58:00] == Registered application 'PrivacyManager' [Mar 19 22:58:00] [app_db.so][Mar 19 22:58:00] => (Database Access Functions) [Mar 19 22:58:00] == Registered application 'DBget' [Mar 19 22:58:00] == Registered application 'DBput' [Mar 19 22:58:00] == Registered application 'DBdel' [Mar 19 22:58:00] == Registered application 'DBdeltree' [Mar 19 22:58:00] [app_chanisavail.so][Mar 19 22:58:00] => (Check channel availability) [Mar 19 22:58:00] == Registered application 'ChanIsAvail' [Mar 19 22:58:00] [app_enumlookup.so][Mar 19 22:58:00] => (ENUM Lookup) [Mar 19 22:58:00] == Registered application 'EnumLookup' [Mar 19 22:58:00] == Parsing '/etc/asterisk/enum.conf': [Mar 19 22:58:00] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/enum.conf [Mar 19 22:58:00] Found [Mar 19 22:58:00] [app_transfer.so][Mar 19 22:58:00] => (Transfer) [Mar 19 22:58:00] == Registered application 'Transfer' [Mar 19 22:58:00] [app_setcidnum.so][Mar 19 22:58:00] => (Set CallerID Number) [Mar 19 22:58:00] == Registered application 'SetCIDNum' [Mar 19 22:58:00] [app_cdr.so][Mar 19 22:58:00] => (Tell Asterisk to not maintain a CDR for the current call) [Mar 19 22:58:00] == Registered application 'NoCDR' [Mar 19 22:58:00] [app_hasnewvoicemail.so][Mar 19 22:58:00] => (Indicator for whether a voice mailbox has messages in a given folder.) [Mar 19 22:58:00] == Registered custom function VMCOUNT [Mar 19 22:58:00] == Registered application 'HasVoicemail' [Mar 19 22:58:00] == Registered application 'HasNewVoicemail' [Mar 19 22:58:00] [app_sayunixtime.so][Mar 19 22:58:00] => (Say time) [Mar 19 22:58:00] == Registered application 'SayUnixTime' [Mar 19 22:58:00] == Registered application 'DateTime' [Mar 19 22:58:00] [app_cut.so][Mar 19 22:58:00] => (Cut out information from a string) [Mar 19 22:58:00] == Registered custom function CUT [Mar 19 22:58:00] == Registered custom function SORT [Mar 19 22:58:00] == Registered application 'Sort' [Mar 19 22:58:00] == Registered application 'Cut' [Mar 19 22:58:00] [app_read.so][Mar 19 22:58:01] => (Read Variable Application) [Mar 19 22:58:01] == Registered application 'Read' [Mar 19 22:58:01] [app_setcdruserfield.so][Mar 19 22:58:01] => (CDR user field apps) [Mar 19 22:58:01] == Registered application 'SetCDRUserField' [Mar 19 22:58:01] == Registered application 'AppendCDRUserField' [Mar 19 22:58:01] == Manager registered action SetCDRUserField [Mar 19 22:58:01] [app_random.so][Mar 19 22:58:01] => (Random goto) [Mar 19 22:58:01] == Registered application 'Random' [Mar 19 22:58:01] [app_ices.so][Mar 19 22:58:01] => (Encode and Stream via icecast and ices) [Mar 19 22:58:01] == Registered application 'ICES' [Mar 19 22:58:01] [app_eval.so][Mar 19 22:58:01] => (Reevaluates strings) [Mar 19 22:58:01] == Registered application 'Eval' [Mar 19 22:58:01] [app_nbscat.so][Mar 19 22:58:01] => (Silly NBS Stream Application) [Mar 19 22:58:01] == Registered application 'NBScat' [Mar 19 22:58:01] [app_sendtext.so][Mar 19 22:58:01] => (Send Text Applications) [Mar 19 22:58:01] == Registered application 'SendText' [Mar 19 22:58:01] [app_exec.so][Mar 19 22:58:01] => (Executes applications) [Mar 19 22:58:01] == Registered application 'Exec' [Mar 19 22:58:01] [app_groupcount.so][Mar 19 22:58:01] => (Group Management Routines) [Mar 19 22:58:01] == Registered application 'GetGroupCount' [Mar 19 22:58:01] == Registered application 'SetGroup' [Mar 19 22:58:01] == Registered application 'CheckGroup' [Mar 19 22:58:01] == Registered application 'GetGroupMatchCount' [Mar 19 22:58:01] [app_txtcidname.so][Mar 19 22:58:01] => (TXTCIDName) [Mar 19 22:58:01] == Registered application 'TXTCIDName' [Mar 19 22:58:01] [app_controlplayback.so][Mar 19 22:58:01] => (Control Playback Application) [Mar 19 22:58:01] == Registered application 'ControlPlayback' [Mar 19 22:58:01] [app_talkdetect.so][Mar 19 22:58:01] => (Playback with Talk Detection) [Mar 19 22:58:01] == Registered application 'BackgroundDetect' [Mar 19 22:58:01] [app_alarmreceiver.so][Mar 19 22:58:01] => (Alarm Receiver for Asterisk) [Mar 19 22:58:01] == Parsing '/etc/asterisk/alarmreceiver.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/alarmreceiver.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] == Registered application 'AlarmReceiver' [Mar 19 22:58:01] [app_userevent.so][Mar 19 22:58:01] => (Custom User Event Application) [Mar 19 22:58:01] == Registered application 'UserEvent' [Mar 19 22:58:01] [app_verbose.so][Mar 19 22:58:01] => (Send verbose output) [Mar 19 22:58:01] == Registered application 'Verbose' [Mar 19 22:58:01] [app_test.so][Mar 19 22:58:01] => (Interface Test Application) [Mar 19 22:58:01] == Registered application 'TestClient' [Mar 19 22:58:01] == Registered application 'TestServer' [Mar 19 22:58:01] [app_forkcdr.so][Mar 19 22:58:01] => (Fork The CDR into 2 separate entities.) [Mar 19 22:58:01] == Registered application 'ForkCDR' [Mar 19 22:58:01] [app_math.so][Mar 19 22:58:01] => (Basic Math Functions) [Mar 19 22:58:01] == Registered application 'Math' [Mar 19 22:58:01] [app_realtime.so][Mar 19 22:58:01] => (Realtime Data Lookup/Rewrite) [Mar 19 22:58:01] == Registered application 'RealTimeUpdate' [Mar 19 22:58:01] == Registered application 'RealTime' [Mar 19 22:58:01] [app_dumpchan.so][Mar 19 22:58:01] => (Dump Info About The Calling Channel) [Mar 19 22:58:01] == Registered application 'DumpChan' [Mar 19 22:58:01] [app_waitforsilence.so][Mar 19 22:58:01] => (Wait For Silence) [Mar 19 22:58:01] == Registered application 'WaitForSilence' [Mar 19 22:58:01] [app_while.so][Mar 19 22:58:01] => (While Loops and Conditional Execution) [Mar 19 22:58:01] == Registered application 'While' [Mar 19 22:58:01] == Registered application 'ExecIf' [Mar 19 22:58:01] == Registered application 'EndWhile' [Mar 19 22:58:01] [app_setrdnis.so][Mar 19 22:58:01] => (Set RDNIS Number) [Mar 19 22:58:01] == Registered application 'SetRDNIS' [Mar 19 22:58:01] [app_md5.so][Mar 19 22:58:01] => (MD5 checksum applications) [Mar 19 22:58:01] == Registered application 'MD5Check' [Mar 19 22:58:01] == Registered application 'MD5' [Mar 19 22:58:01] [app_readfile.so][Mar 19 22:58:01] => (Stores output of file into a variable) [Mar 19 22:58:01] == Registered application 'ReadFile' [Mar 19 22:58:01] [app_chanspy.so][Mar 19 22:58:01] => (Listen to the audio of an active channel) [Mar 19 22:58:01] == Registered application 'ChanSpy' [Mar 19 22:58:01] [app_settransfercapability.so][Mar 19 22:58:01] => (Set ISDN Transfer Capability) [Mar 19 22:58:01] == Registered application 'SetTransferCapability' [Mar 19 22:58:01] [app_dictate.so][Mar 19 22:58:01] => (Virtual Dictation Machine) [Mar 19 22:58:01] == Registered application 'Dictate' [Mar 19 22:58:01] [app_externalivr.so][Mar 19 22:58:01] => (External IVR Interface Application) [Mar 19 22:58:01] == Registered application 'ExternalIVR' [Mar 19 22:58:01] [app_directed_pickup.so][Mar 19 22:58:01] => (Directed Call Pickup Application) [Mar 19 22:58:01] == Registered application 'Pickup' [Mar 19 22:58:01] [app_mixmonitor.so][Mar 19 22:58:01] => (Mixed Audio Monitoring Application) [Mar 19 22:58:01] == Registered application 'MixMonitor' [Mar 19 22:58:01] [app_stack.so][Mar 19 22:58:01] => (Stack Routines) [Mar 19 22:58:01] == Registered application 'StackPop' [Mar 19 22:58:01] == Registered application 'Return' [Mar 19 22:58:01] == Registered application 'GosubIf' [Mar 19 22:58:01] == Registered application 'Gosub' [Mar 19 22:58:01] [app_sms.so][Mar 19 22:58:01] => (SMS/PSTN handler) [Mar 19 22:58:01] == Registered application 'SMS' [Mar 19 22:58:01] [codec_ilbc.so][Mar 19 22:58:01] => (iLBC/PCM16 (signed linear) Codec Translator) [Mar 19 22:58:01] == Registered translator 'ilbctolin' from format ilbc to slin, cost 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] == Registered translator 'lintoilbc' from format slin to ilbc, cost 10 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] [codec_gsm.so][Mar 19 22:58:01] => (GSM/PCM16 (signed linear) Codec Translator) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_gsm: using generic PLC [Mar 19 22:58:01] == Registered translator 'gsmtolin' from format gsm to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'lintogsm' from format slin to gsm, cost 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] [codec_lpc10.so][Mar 19 22:58:01] => (LPC10 2.4kbps (signed linear) Voice Coder) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_lpc10: using generic PLC [Mar 19 22:58:01] == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] == Registered translator 'lintolpc10' from format slin to lpc10, cost 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [codec_adpcm.so][Mar 19 22:58:01] => (Adaptive Differential PCM Coder/Decoder) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_adpcm: using generic PLC [Mar 19 22:58:01] == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [codec_ulaw.so][Mar 19 22:58:01] => (Mu-law Coder/Decoder) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_ulaw: using generic PLC [Mar 19 22:58:01] == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [codec_alaw.so][Mar 19 22:58:01] => (A-law Coder/Decoder) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_alaw: using generic PLC [Mar 19 22:58:01] == Registered translator 'alawtolin' from format alaw to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'lintoalaw' from format slin to alaw, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [codec_a_mu.so][Mar 19 22:58:01] => (A-law and Mulaw direct Coder/Decoder) [Mar 19 22:58:01] == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [codec_g726.so][Mar 19 22:58:01] => (ITU G.726-32kbps G726 Transcoder) [Mar 19 22:58:01] == Parsing '/etc/asterisk/codecs.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/codecs.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] -- codec_g726: using generic PLC [Mar 19 22:58:01] == Registered translator 'g726tolin' from format g726 to slin, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] == Registered translator 'lintog726' from format slin to g726, cost 1 [Mar 19 22:58:01] DEBUG[3942]: translate.c:278 rebuild_matrix: Resetting translation matrix [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from g723 to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from gsm to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to ulaw, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 12 cost path from unknown to unknown, via 2 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from ulaw to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 2 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 11 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to g723, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to gsm, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to ulaw, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 3 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] DEBUG[3942]: translate.c:322 rebuild_matrix: Discovered 4 cost path from unknown to unknown, via 6 [Mar 19 22:58:01] [format_gsm.so][Mar 19 22:58:01] => (Raw GSM data) [Mar 19 22:58:01] == Registered file format gsm, extension(s) gsm [Mar 19 22:58:01] [format_wav.so][Mar 19 22:58:01] => (Microsoft WAV format (8000hz Signed Linear)) [Mar 19 22:58:01] == Registered file format wav, extension(s) wav [Mar 19 22:58:01] [format_wav_gsm.so][Mar 19 22:58:01] => (Microsoft WAV format (Proprietary GSM)) [Mar 19 22:58:01] == Registered file format wav49, extension(s) WAV|wav49 [Mar 19 22:58:01] [format_vox.so][Mar 19 22:58:01] => (Dialogic VOX (ADPCM) File Format) [Mar 19 22:58:01] == Registered file format vox, extension(s) vox [Mar 19 22:58:01] [format_pcm.so][Mar 19 22:58:01] => (Raw uLaw 8khz Audio support (PCM)) [Mar 19 22:58:01] == Registered file format pcm, extension(s) pcm|ulaw|ul|mu [Mar 19 22:58:01] [format_g729.so][Mar 19 22:58:01] => (Raw G729 data) [Mar 19 22:58:01] == Registered file format g729, extension(s) g729 [Mar 19 22:58:01] [format_pcm_alaw.so][Mar 19 22:58:01] => (Raw aLaw 8khz PCM Audio support) [Mar 19 22:58:01] == Registered file format alaw, extension(s) alaw|al [Mar 19 22:58:01] [format_h263.so][Mar 19 22:58:01] => (Raw h263 data) [Mar 19 22:58:01] == Registered file format h263, extension(s) h263 [Mar 19 22:58:01] [format_g726.so][Mar 19 22:58:01] => (Raw G.726 (16/24/32/40kbps) data) [Mar 19 22:58:01] == Registered file format g726-40, extension(s) g726-40 [Mar 19 22:58:01] == Registered file format g726-32, extension(s) g726-32 [Mar 19 22:58:01] == Registered file format g726-24, extension(s) g726-24 [Mar 19 22:58:01] == Registered file format g726-16, extension(s) g726-16 [Mar 19 22:58:01] [format_ilbc.so][Mar 19 22:58:01] => (Raw iLBC data) [Mar 19 22:58:01] == Registered file format iLBC, extension(s) ilbc [Mar 19 22:58:01] [format_sln.so][Mar 19 22:58:01] => (Raw Signed Linear Audio support (SLN)) [Mar 19 22:58:01] == Registered file format sln, extension(s) sln|raw [Mar 19 22:58:01] [format_au.so][Mar 19 22:58:01] => (Sun Microsystems AU format (signed linear)) [Mar 19 22:58:01] == Registered file format au, extension(s) au [Mar 19 22:58:01] [format_jpeg.so][Mar 19 22:58:01] => (JPEG (Joint Picture Experts Group) Image Format) [Mar 19 22:58:01] == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) [Mar 19 22:58:01] [format_g723.so][Mar 19 22:58:01] => (G.723.1 Simple Timestamp File Format) [Mar 19 22:58:01] == Registered file format g723sf, extension(s) g723|g723sf [Mar 19 22:58:01] [cdr_csv.so][Mar 19 22:58:01] => (Comma Separated Values CDR Backend) [Mar 19 22:58:01] [cdr_manager.so][Mar 19 22:58:01] => (Asterisk Call Manager CDR Backend) [Mar 19 22:58:01] == Parsing '/etc/asterisk/cdr_manager.conf': [Mar 19 22:58:01] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr_manager.conf [Mar 19 22:58:01] Found [Mar 19 22:58:01] [cdr_custom.so][Mar 19 22:58:02] => (Customizable Comma Separated Values CDR Backend) [Mar 19 22:58:02] == Parsing '/etc/asterisk/cdr_custom.conf': [Mar 19 22:58:02] DEBUG[3942]: config.c:598 config_text_file_load: Parsing /etc/asterisk/cdr_custom.conf [Mar 19 22:58:02] Found [Mar 19 22:58:02] [func_callerid.so][Mar 19 22:58:02] => (Caller ID related dialplan function) [Mar 19 22:58:02] == Registered custom function CALLERID [Mar 19 22:58:02] [func_enum.so][Mar 19 22:58:02] => (ENUM Related Functions) [Mar 19 22:58:02] == Registered custom function ENUMLOOKUP [Mar 19 22:58:02] == Registered custom function TXTCIDNAME [Mar 19 22:58:02] [func_uri.so][Mar 19 22:58:02] => (URI encode/decode functions) [Mar 19 22:58:02] == Registered custom function URIDECODE [Mar 19 22:58:02] == Registered custom function URIENCODE [Mar 19 22:58:02] Asterisk Ready. ]1;Asterisk]2;Asterisk Console on 'Asterisk' (pid 3942)*CLI> set debug 34 Core debug was 5 and is now 4 *CLI> set core 4 No such command 'set core' (type 'help' for help) *CLI> core set verbose 544 No such command 'core' (type 'help' for help) *CLI> se[Mar 19 22:58:32] DEBUG[3953]: chan_sip.c:1336 __sip_autodestruct: Auto destroying call '4c2de941586fa468124537f0533b8da0@192.168.1.15' t set verbose 4 No such command 'seset' (type 'help' for help) *CLI> vess send set show sip skinny soft stop *CLI> se send set *CLI> se send set *CLI> se send set *CLI> set verbose 4 Verbosity was 5 and is now 4 *CLI> sip debug SIP Debugging enabled *CLI> [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:59:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:voip.eutelia.it SIP/2.0 (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK2b8a25de;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as5db49f6f (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (25) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 28de846a159129827ff346416d700d76@62.94.37.175 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 21:59:00 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 22:59:00] 12 headers, 0 lines [Mar 19 22:59:00] Reliably Transmitting (NAT) to 83.211.227.21:5060: OPTIONS sip:voip.eutelia.it SIP/2.0 Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK2b8a25de;rport From: "asterisk" ;tag=as5db49f6f To: Contact: Call-ID: 28de846a159129827ff346416d700d76@62.94.37.175 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 21:59:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #30 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:59:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:4000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0d03ef19;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as67db14ae (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 21:59:00 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 22:59:00] 12 headers, 0 lines [Mar 19 22:59:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:4000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0d03ef19;rport From: "asterisk" ;tag=as67db14ae To: Contact: Call-ID: 024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 21:59:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #32 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:59:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:3000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0b050842;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as535934a7 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 717675c43378e40f351bacf6108c0f58@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 21:59:00 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 22:59:00] 12 headers, 0 lines [Mar 19 22:59:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:3000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0b050842;rport From: "asterisk" ;tag=as535934a7 To: Contact: Call-ID: 717675c43378e40f351bacf6108c0f58@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 21:59:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #34 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:59:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:2000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK215980ab;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as1dd72f68 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 165f12994940ba933e291960793a6d34@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 21:59:00 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 22:59:00] 12 headers, 0 lines [Mar 19 22:59:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:2000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK215980ab;rport From: "asterisk" ;tag=as1dd72f68 To: Contact: Call-ID: 165f12994940ba933e291960793a6d34@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 21:59:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #36 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 22:59:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:1000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK44d2432d;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as1bf6d3e8 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 21:59:00 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 22:59:00] 12 headers, 0 lines [Mar 19 22:59:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:1000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK44d2432d;rport From: "asterisk" ;tag=as1bf6d3e8 To: Contact: Call-ID: 6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 21:59:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #38 [Mar 19 22:59:00] <-- SIP read from 83.211.227.21:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2b8a25de;rport=5060 From: "asterisk" ;tag=as5db49f6f To: ;tag=d7cbdeb4f107ce82ed834cadd3d6dbb2.8d02 Call-ID: 28de846a159129827ff346416d700d76@62.94.37.175 CSeq: 102 OPTIONS Accept: */* Accept-Encoding: Accept-Language: en Support: Server: SPS EUT GW 01 (0.9.6 (i386/linux)) Content-Length: 0 Warning: 392 83.211.227.21:5060 "Noisy feedback tells: pid=22176 req_src_ip=62.94.37.175 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==1" [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2b8a25de;rport=5060 (68) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as5db49f6f (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=d7cbdeb4f107ce82ed834cadd3d6dbb2.8d02 (67) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Call-ID: 28de846a159129827ff346416d700d76@62.94.37.175 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Accept: */* (11) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: Accept-Encoding: (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Accept-Language: en (19) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Support: (9) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Server: SPS EUT GW 01 (0.9.6 (i386/linux)) (42) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Warning: 392 83.211.227.21:5060 "Noisy feedback tells: pid=22176 req_src_ip=62.94.37.175 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==1" (174) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: (0) [Mar 19 22:59:00] --- (13 headers 0 lines) --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #30 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '28de846a159129827ff346416d700d76@62.94.37.175' of Request 102: Match Found [Mar 19 22:59:00] Destroying call '28de846a159129827ff346416d700d76@62.94.37.175' [Mar 19 22:59:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0d03ef19;rport From: "asterisk" ;tag=as67db14ae To: ;tag=5DC285-1E6A Date: Tue, 19 Mar 2002 15:16:35 GMT Call-ID: 024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 162 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 74 9571 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0d03ef19;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as67db14ae (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5DC285-1E6A (49) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:16:35 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 162 (19) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 74 9571 IN IP4 62.94.37.175 (58) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] --- (14 headers 7 lines) --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #32 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15' of Request 102: Match Found [Mar 19 22:59:00] Destroying call '024d586e2f9b85a06e4b2bc704e300fa@192.168.1.15' [Mar 19 22:59:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0b050842;rport From: "asterisk" ;tag=as535934a7 To: ;tag=5DC2B5-1A41 Date: Tue, 19 Mar 2002 15:16:35 GMT Call-ID: 717675c43378e40f351bacf6108c0f58@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 6763 1799 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0b050842;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as535934a7 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5DC2B5-1A41 (49) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:16:35 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 717675c43378e40f351bacf6108c0f58@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 6763 1799 IN IP4 62.94.37.175 (60) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] --- (14 headers 7 lines) --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #34 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '717675c43378e40f351bacf6108c0f58@192.168.1.15' of Request 102: Match Found [Mar 19 22:59:00] Destroying call '717675c43378e40f351bacf6108c0f58@192.168.1.15' [Mar 19 22:59:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK215980ab;rport From: "asterisk" ;tag=as1dd72f68 To: ;tag=5DC2E1-D7A Date: Tue, 19 Mar 2002 15:16:35 GMT Call-ID: 165f12994940ba933e291960793a6d34@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 7081 9782 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK215980ab;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as1dd72f68 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5DC2E1-D7A (48) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:16:35 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 165f12994940ba933e291960793a6d34@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 7081 9782 IN IP4 62.94.37.175 (60) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] --- (14 headers 7 lines) --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #36 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '165f12994940ba933e291960793a6d34@192.168.1.15' of Request 102: Match Found [Mar 19 22:59:00] Destroying call '165f12994940ba933e291960793a6d34@192.168.1.15' [Mar 19 22:59:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK44d2432d;rport From: "asterisk" ;tag=as1bf6d3e8 To: ;tag=5DC30D-20A9 Date: Tue, 19 Mar 2002 15:16:35 GMT Call-ID: 6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 1288 1817 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK44d2432d;rport (63) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as1bf6d3e8 (59) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5DC30D-20A9 (49) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:16:35 GMT (35) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15 (54) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 1288 1817 IN IP4 62.94.37.175 (60) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 22:59:00] --- (14 headers 7 lines) --- [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #38 [Mar 19 22:59:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15' of Request 102: Match Found [Mar 19 22:59:00] Destroying call '6768287f08b8ff0d4970d6df6e50cbb7@192.168.1.15' [Mar 19 22:59:07] <-- SIP read from 83.211.227.21:5060: INVITE sip:09611915350@192.168.1.15 SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 83.211.227.21;branch=0 Via: SIP/2.0/UDP 83.211.227.14;branch=z9hG4bK2693.28ac4547.0 Via: SIP/2.0/UDP 83.211.2.216:5060;rport=53430;branch=z9hG4bK781CE2AC From: ;tag=26C9B614-29E To: Call-ID: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 Supported: rel1xx,timer,replaces CSeq: 102 INVITE Max-Forwards: 8 Remote-Party-ID: ;party=calling;screen=yes;privacy=off Contact: Expires: 180 Content-Type: application/sdp Content-Length: 450 [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: INVITE sip:09611915350@192.168.1.15 SIP/2.0 (43) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Record-Route: (57) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: Record-Route: (57) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: Via: SIP/2.0/UDP 83.211.227.21;branch=0 (39) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Via: SIP/2.0/UDP 83.211.227.14;branch=z9hG4bK2693.28ac4547.0 (60) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Via: SIP/2.0/UDP 83.211.2.216:5060;rport=53430;branch=z9hG4bK781CE2AC (70) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: From: ;tag=26C9B614-29E (52) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: To: (36) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Call-ID: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 (57) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Supported: rel1xx,timer,replaces (32) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: CSeq: 102 INVITE (16) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Max-Forwards: 8 (16) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Remote-Party-ID: ;party=calling;screen=yes;privacy=off (83) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Contact: (43) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: Expires: 180 (12) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 15: Content-Type: application/sdp (29) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 16: Content-Length: 450 (19) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 17: (0) [Mar 19 22:59:07] --- (17 headers 0 lines) --- [Mar 19 22:59:07] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 - INVITE (With RTP) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:11296 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1015 parse_sip_options: Begin: parsing SIP "Supported: rel1xx,timer,replaces" [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1027 parse_sip_options: Found SIP option: -rel1xx- [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1038 parse_sip_options: Found no match for SIP option: rel1xx (Please file bug report!) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1027 parse_sip_options: Found SIP option: -timer- [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1033 parse_sip_options: Matched SIP option: timer [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1027 parse_sip_options: Found SIP option: -replaces- [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1033 parse_sip_options: Matched SIP option: replaces [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:1044 parse_sip_options: * SIP extension value: 5 for call E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 [Mar 19 22:59:07] Using INVITE request as basis request - E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 [Mar 19 22:59:07] Sending to 83.211.227.21 : 5060 (NAT) [Mar 19 22:59:07] Found peer 'eutelia-out' [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:7370 check_user_full: Setting NAT on RTP to 524288 [Mar 19 22:59:07] WARNING[3953]: chan_sip.c:3598 process_sdp: Insufficient information for SDP (m = '', c = '') [Mar 19 22:59:07] Transmitting (NAT) to 83.211.227.21:5060: SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 83.211.227.21;branch=0;received=83.211.227.21 Via: SIP/2.0/UDP 83.211.227.14;branch=z9hG4bK2693.28ac4547.0 Via: SIP/2.0/UDP 83.211.2.216:5060;rport=53430;branch=z9hG4bK781CE2AC From: ;tag=26C9B614-29E To: ;tag=as6c41f611 Call-ID: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:11500 sipsock_read: SIP message could not be handled, bad request: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 [Mar 19 22:59:07] Destroying call 'E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216' [Mar 19 22:59:07] <-- SIP read from 83.211.227.21:5060: ACK sip:09611915350@192.168.1.15 SIP/2.0 Max-Forwards: 15 Record-Route: Via: SIP/2.0/UDP 83.211.227.21;branch=0 Via: SIP/2.0/UDP 83.211.227.14;branch=z9hG4bK2693.28ac4547.0 From: ;tag=26C9B614-29E Call-ID: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 To: ;tag=as6c41f611 CSeq: 102 ACK Content-Length: 0 [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: ACK sip:09611915350@192.168.1.15 SIP/2.0 (40) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Max-Forwards: 15 (16) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: Record-Route: (57) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: Via: SIP/2.0/UDP 83.211.227.21;branch=0 (39) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Via: SIP/2.0/UDP 83.211.227.14;branch=z9hG4bK2693.28ac4547.0 (60) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: From: ;tag=26C9B614-29E (52) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Call-ID: E4373E8F-D59B11DB-96D1FE05-7280E5CA@83.211.2.216 (57) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: To: ;tag=as6c41f611 (51) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: CSeq: 102 ACK (13) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Content-Length: 0 (17) [Mar 19 22:59:07] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: (0) [Mar 19 22:59:07] --- (10 headers 0 lines) --- [Mar 19 22:59:45] NOTICE[3953]: chan_sip.c:5460 sip_reregister: -- Re-registration for 09611915350@voip.eutelia.it [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for 4c2de941586fa468124537f0533b8da0@192.168.1.15 - REGISTER (No RTP) [Mar 19 22:59:45] -- parse_srv: SRV mapped to host voip.eutelia.it, port 5060 [Mar 19 22:59:45] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:5625 transmit_register: Scheduled a registration timeout for voip.eutelia.it id #45 [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:5676 transmit_register: >>> Re-using Auth data for 09611915350@voip.eutelia.it [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: REGISTER sip:voip.eutelia.it SIP/2.0 (36) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK1880c287;rport (63) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: ;tag=as34b6b448 (54) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (37) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Call-ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 (54) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: CSeq: 104 REGISTER (18) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: User-Agent: Asterisk PBX (24) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: Max-Forwards: 70 (16) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Authorization: Digest username="09611915350", realm="voip.eutelia.it", algorithm=MD5, uri="sip:voip.eutelia.it", nonce="45ff089212ef43e2dc34bc1b21ad4c4428990d94", response="80f5da109c314dd235da92f1fcdf2d2a", opaque="", qop=auth, cnonce="1e276a10", nc=00000002 (259) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Expires: 120 (12) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Contact: (39) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Event: registration (19) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 0 (17) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: (0) [Mar 19 22:59:45] REGISTER 13 headers, 0 lines [Mar 19 22:59:45] REGISTER attempt 1 to 09611915350@voip.eutelia.it [Mar 19 22:59:45] Reliably Transmitting (NAT) to 83.211.227.21:5060: REGISTER sip:voip.eutelia.it SIP/2.0 Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK1880c287;rport From: ;tag=as34b6b448 To: Call-ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 CSeq: 104 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Authorization: Digest username="09611915350", realm="voip.eutelia.it", algorithm=MD5, uri="sip:voip.eutelia.it", nonce="45ff089212ef43e2dc34bc1b21ad4c4428990d94", response="80f5da109c314dd235da92f1fcdf2d2a", opaque="", qop=auth, cnonce="1e276a10", nc=00000002 Expires: 120 Contact: Event: registration Content-Length: 0 --- [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #46 [Mar 19 22:59:45] <-- SIP read from 83.211.227.21:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK1880c287;rport=5060 From: ;tag=as34b6b448 To: ;tag=d5ce1f561fa195512de35a1851da2a84.37d1 Call-ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 CSeq: 104 REGISTER Date: Mon, 19 Mar 2007 21:59:44 GMT Contact: ;q=0.5;expires=300 Server: SPS EUT GW 01 (0.9.6 (i386/linux)) Content-Length: 0 Warning: 392 83.211.227.14:5060 "Noisy feedback tells: pid=17714 req_src_ip=83.211.227.21 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==2" [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK1880c287;rport=5060 (68) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: ;tag=as34b6b448 (54) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=d5ce1f561fa195512de35a1851da2a84.37d1 (79) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Call-ID: 4c2de941586fa468124537f0533b8da0@192.168.1.15 (54) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: CSeq: 104 REGISTER (18) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Date: Mon, 19 Mar 2007 21:59:44 GMT (35) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: Contact: ;q=0.5;expires=300 (57) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Server: SPS EUT GW 01 (0.9.6 (i386/linux)) (42) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Content-Length: 0 (17) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Warning: 392 83.211.227.14:5060 "Noisy feedback tells: pid=17714 req_src_ip=83.211.227.21 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==2" (175) [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: (0) [Mar 19 22:59:45] --- (11 headers 0 lines) --- [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #46 [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '4c2de941586fa468124537f0533b8da0@192.168.1.15' of Request 104: Match Found [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:9927 handle_response_register: Registration successful [Mar 19 22:59:45] DEBUG[3953]: chan_sip.c:9929 handle_response_register: Cancelling timeout 45 [Mar 19 22:59:45] Scheduling destruction of call '4c2de941586fa468124537f0533b8da0@192.168.1.15' in 32000 ms [Mar 19 22:59:45] NOTICE[3953]: chan_sip.c:9979 handle_response_register: Outbound Registration: Expiry for voip.eutelia.it is 120 sec (Scheduling reregistration in 105 s) *CLI> [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 23:00:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 83.211.227.21 with 192.168.1.0 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1129 ast_sip_ouraddrfor: Target address 83.211.227.21 is not local, substituting externip [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:voip.eutelia.it SIP/2.0 (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK36477b06;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as26905956 (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (25) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 3cb863213caba9622a1bb6d813f844e5@62.94.37.175 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 22:00:00 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 23:00:00] 12 headers, 0 lines [Mar 19 23:00:00] Reliably Transmitting (NAT) to 83.211.227.21:5060: OPTIONS sip:voip.eutelia.it SIP/2.0 Via: SIP/2.0/UDP 62.94.37.175:5060;branch=z9hG4bK36477b06;rport From: "asterisk" ;tag=as26905956 To: Contact: Call-ID: 3cb863213caba9622a1bb6d813f844e5@62.94.37.175 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 22:00:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #49 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 23:00:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:4000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK766af8a3;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as0e7081ec (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 7492e56962de113067c1122a1c61d4b8@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 22:00:00 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 23:00:00] 12 headers, 0 lines [Mar 19 23:00:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:4000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK766af8a3;rport From: "asterisk" ;tag=as0e7081ec To: Contact: Call-ID: 7492e56962de113067c1122a1c61d4b8@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 22:00:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #51 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 23:00:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:3000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2014c8f2;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as5ae7d527 (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 343b47fd6956f4f379e3044c27a2a6da@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 22:00:00 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 23:00:00] 12 headers, 0 lines [Mar 19 23:00:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:3000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2014c8f2;rport From: "asterisk" ;tag=as5ae7d527 To: Contact: Call-ID: 343b47fd6956f4f379e3044c27a2a6da@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 22:00:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #53 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 23:00:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:2000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0c66179a;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as0c68238f (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 22:00:00 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 23:00:00] 12 headers, 0 lines [Mar 19 23:00:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:2000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0c66179a;rport From: "asterisk" ;tag=as0c68238f To: Contact: Call-ID: 049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 22:00:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #55 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3191 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 19 23:00:00] DEBUG[3953]: acl.c:211 ast_apply_ha: ##### Testing 192.168.1.254 with 192.168.1.0 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: OPTIONS sip:1000@192.168.1.254:5060 SIP/2.0 (43) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK4003f916;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as56bcda9d (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Contact: (36) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 6e191b9b3789f27b25dabe324e8616ab@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: User-Agent: Asterisk PBX (24) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Max-Forwards: 70 (16) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Date: Mon, 19 Mar 2007 22:00:00 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: (0) [Mar 19 23:00:00] 12 headers, 0 lines [Mar 19 23:00:00] Reliably Transmitting (no NAT) to 192.168.1.254:5060: OPTIONS sip:1000@192.168.1.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK4003f916;rport From: "asterisk" ;tag=as56bcda9d To: Contact: Call-ID: 6e191b9b3789f27b25dabe324e8616ab@192.168.1.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Mon, 19 Mar 2007 22:00:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1307 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #57 [Mar 19 23:00:00] <-- SIP read from 83.211.227.21:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK36477b06;rport=5060 From: "asterisk" ;tag=as26905956 To: ;tag=d7cbdeb4f107ce82ed834cadd3d6dbb2.8e67 Call-ID: 3cb863213caba9622a1bb6d813f844e5@62.94.37.175 CSeq: 102 OPTIONS Accept: */* Accept-Encoding: Accept-Language: en Support: Server: SPS EUT GW 01 (0.9.6 (i386/linux)) Content-Length: 0 Warning: 392 83.211.227.21:5060 "Noisy feedback tells: pid=22186 req_src_ip=62.94.37.175 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==1" [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK36477b06;rport=5060 (68) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as26905956 (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=d7cbdeb4f107ce82ed834cadd3d6dbb2.8e67 (67) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Call-ID: 3cb863213caba9622a1bb6d813f844e5@62.94.37.175 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Accept: */* (11) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: Accept-Encoding: (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Accept-Language: en (19) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Support: (9) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Server: SPS EUT GW 01 (0.9.6 (i386/linux)) (42) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Content-Length: 0 (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Warning: 392 83.211.227.21:5060 "Noisy feedback tells: pid=22186 req_src_ip=62.94.37.175 req_src_port=5060 in_uri=sip:voip.eutelia.it out_uri=sip:voip.eutelia.it via_cnt==1" (174) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: (0) [Mar 19 23:00:00] --- (13 headers 0 lines) --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #49 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '3cb863213caba9622a1bb6d813f844e5@62.94.37.175' of Request 102: Match Found [Mar 19 23:00:00] Destroying call '3cb863213caba9622a1bb6d813f844e5@62.94.37.175' [Mar 19 23:00:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK766af8a3;rport From: "asterisk" ;tag=as0e7081ec To: ;tag=5EADC5-E31 Date: Tue, 19 Mar 2002 15:17:35 GMT Call-ID: 7492e56962de113067c1122a1c61d4b8@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 9330 6770 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK766af8a3;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as0e7081ec (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5EADC5-E31 (48) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:17:35 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 7492e56962de113067c1122a1c61d4b8@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 9330 6770 IN IP4 62.94.37.175 (60) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] --- (14 headers 7 lines) --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #51 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '7492e56962de113067c1122a1c61d4b8@192.168.1.15' of Request 102: Match Found [Mar 19 23:00:00] Destroying call '7492e56962de113067c1122a1c61d4b8@192.168.1.15' [Mar 19 23:00:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2014c8f2;rport From: "asterisk" ;tag=as5ae7d527 To: ;tag=5EADF5-1C9F Date: Tue, 19 Mar 2002 15:17:35 GMT Call-ID: 343b47fd6956f4f379e3044c27a2a6da@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 2488 9307 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK2014c8f2;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as5ae7d527 (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5EADF5-1C9F (49) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:17:35 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 343b47fd6956f4f379e3044c27a2a6da@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 2488 9307 IN IP4 62.94.37.175 (60) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] --- (14 headers 7 lines) --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #53 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '343b47fd6956f4f379e3044c27a2a6da@192.168.1.15' of Request 102: Match Found [Mar 19 23:00:00] Destroying call '343b47fd6956f4f379e3044c27a2a6da@192.168.1.15' [Mar 19 23:00:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0c66179a;rport From: "asterisk" ;tag=as0c68238f To: ;tag=5EAE45-128A Date: Tue, 19 Mar 2002 15:17:35 GMT Call-ID: 049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 5236 1861 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK0c66179a;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as0c68238f (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5EAE45-128A (49) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:17:35 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 5236 1861 IN IP4 62.94.37.175 (60) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] --- (14 headers 7 lines) --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #55 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15' of Request 102: Match Found [Mar 19 23:00:00] Destroying call '049ac49e42c3ddfe3bfe4b336be5a087@192.168.1.15' [Mar 19 23:00:00] <-- SIP read from 192.168.1.254:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK4003f916;rport From: "asterisk" ;tag=as56bcda9d To: ;tag=5EAE75-214F Date: Tue, 19 Mar 2002 15:17:35 GMT Call-ID: 6e191b9b3789f27b25dabe324e8616ab@192.168.1.15 Server: Cisco-SIPGateway/IOS-12.x CSeq: 102 OPTIONS Supported: 100rel,replaces Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER Accept: application/sdp Allow-Events: telephone-event Content-Length: 164 Content-Type: application/sdp v=0 o=CiscoSystemsSIP-GW-UserAgent 5360 3610 IN IP4 62.94.37.175 s=SIP Call c=IN IP4 62.94.37.175 t=0 0 m=audio 0 RTP/AVP 18 0 8 4 2 0 c=IN IP4 62.94.37.175 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 0: SIP/2.0 200 OK (14) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 1: Via: SIP/2.0/UDP 192.168.1.15:5060;branch=z9hG4bK4003f916;rport (63) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 2: From: "asterisk" ;tag=as56bcda9d (59) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 3: To: ;tag=5EAE75-214F (49) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 4: Date: Tue, 19 Mar 2002 15:17:35 GMT (35) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 5: Call-ID: 6e191b9b3789f27b25dabe324e8616ab@192.168.1.15 (54) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 6: Server: Cisco-SIPGateway/IOS-12.x (33) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 7: CSeq: 102 OPTIONS (17) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 8: Supported: 100rel,replaces (26) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 9: Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER (104) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 10: Accept: application/sdp (23) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 11: Allow-Events: telephone-event (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 12: Content-Length: 164 (19) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 13: Content-Type: application/sdp (29) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3442 parse_request: Header 14: (0) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: v=0 (3) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: o=CiscoSystemsSIP-GW-UserAgent 5360 3610 IN IP4 62.94.37.175 (60) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: s=SIP Call (10) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: t=0 0 (5) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: m=audio 0 RTP/AVP 18 0 8 4 2 0 (30) [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:3474 parse_request: Line: c=IN IP4 62.94.37.175 (21) [Mar 19 23:00:00] --- (14 headers 7 lines) --- [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1403 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #57 [Mar 19 23:00:00] DEBUG[3953]: chan_sip.c:1415 __sip_ack: Stopping retransmission on '6e191b9b3789f27b25dabe324e8616ab@192.168.1.15' of Request 102: Match Found [Mar 19 23:00:00] Destroying call '6e191b9b3789f27b25dabe324e8616ab@192.168.1.15'