Nov 6 10:13:36 VERBOSE[17464] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/dnsmgr.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/dnsmgr.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/dnsmgr.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: Asterisk Dynamic Loader loading preload modules: Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/modules.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/modules.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/modules.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Ping Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Events Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Logoff Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Hangup Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Status Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Setvar Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Getvar Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Redirect Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Originate Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Command Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ExtensionState Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action AbsoluteTimeout Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action MailboxStatus Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action MailboxCount Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ListCommands Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/manager.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/manager.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/manager.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/cdr.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr.conf': Found Nov 6 10:13:36 NOTICE[17464] cdr.c: CDR simple logging enabled. Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/rtp.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/rtp.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/rtp.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: == RTP Allocating from port range 10000 -> 20000 Nov 6 10:13:36 VERBOSE[17464] logger.c: Asterisk PBX Core Initializing Nov 6 10:13:36 VERBOSE[17464] logger.c: Registering builtin applications: Nov 6 10:13:36 VERBOSE[17464] logger.c: [AbsoluteTimeout] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'AbsoluteTimeout' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Answer] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Answer' Nov 6 10:13:36 VERBOSE[17464] logger.c: [BackGround] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'BackGround' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Busy] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Busy' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Congestion] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Congestion' Nov 6 10:13:36 VERBOSE[17464] logger.c: [DigitTimeout] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'DigitTimeout' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Goto] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Goto' Nov 6 10:13:36 VERBOSE[17464] logger.c: [GotoIf] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'GotoIf' Nov 6 10:13:36 VERBOSE[17464] logger.c: [GotoIfTime] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'GotoIfTime' Nov 6 10:13:36 VERBOSE[17464] logger.c: [ExecIfTime] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ExecIfTime' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Hangup] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Hangup' Nov 6 10:13:36 VERBOSE[17464] logger.c: [NoOp] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'NoOp' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Progress] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Progress' Nov 6 10:13:36 VERBOSE[17464] logger.c: [ResetCDR] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ResetCDR' Nov 6 10:13:36 VERBOSE[17464] logger.c: [ResponseTimeout] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ResponseTimeout' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Ringing] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Ringing' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SayNumber] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SayNumber' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SayDigits] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SayDigits' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SayAlpha] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SayAlpha' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SayPhonetic] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SayPhonetic' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SetAccount] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetAccount' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SetAMAFlags] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetAMAFlags' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SetGlobalVar] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetGlobalVar' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SetLanguage] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetLanguage' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Set] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Set' Nov 6 10:13:36 VERBOSE[17464] logger.c: [SetVar] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetVar' Nov 6 10:13:36 VERBOSE[17464] logger.c: [ImportVar] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ImportVar' Nov 6 10:13:36 VERBOSE[17464] logger.c: [Wait] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Wait' Nov 6 10:13:36 VERBOSE[17464] logger.c: [WaitExten] Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'WaitExten' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action DBGet Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action DBPut Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/enum.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/enum.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/enum.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: Asterisk Dynamic Loader Starting: Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/modules.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/modules.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/modules.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_musiconhold.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_musiconhold.so] => (Music On Hold Resource) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'MusicOnHold' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'WaitMusicOnHold' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'SetMusicOnHold' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'StartMusicOnHold' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'StopMusicOnHold' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/musiconhold.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/musiconhold.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/musiconhold.conf': Found Nov 6 10:13:36 WARNING[17464] res_musiconhold.c: Unable to open pseudo channel for timing... Sound may be choppy. Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_crypto.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_crypto.so] => (Cryptographic Digital Signatures) Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Loaded PUBLIC key 'freeworlddialup' Nov 6 10:13:36 DEBUG[17464] res_crypto.c: Key 'freeworlddialup' loaded OK Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Loaded PUBLIC key 'iaxtel' Nov 6 10:13:36 DEBUG[17464] res_crypto.c: Key 'iaxtel' loaded OK Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_monitor.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_monitor.so] => (Call Monitoring Resource) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Monitor' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'StopMonitor' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ChangeMonitor' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Monitor Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action StopMonitor Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ChangeMonitor Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_indications.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_indications.so] => (Indications Configuration) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/indications.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/indications.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/indications.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'at' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'au' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'br' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'be' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'ch' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'cl' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'cn' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'cz' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'de' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'dk' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'ee' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'es' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'fi' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'fr' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'gr' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'hu' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'it' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'lt' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'mx' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'nl' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'no' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'nz' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'pl' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'pt' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'ru' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'se' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'sg' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'uk' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'us' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'us-o' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'tw' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered indication country 'za' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Setting default indication country to 'us' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'PlayTones' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'StopPlayTones' Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_adsi.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_adsi.so] => (ADSI Resource) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/adsi.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/adsi.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/adsi.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_features.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_features.so] => (Call Features Resource) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/features.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/features.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/features.conf': Found Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'parkedcalls' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'parkedcalls' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '700' priority 1 to parkedcalls Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '700' priority 1 to parkedcalls Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'ParkedCall' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'Park' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ParkedCalls Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_agi.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [res_agi.so] => (Asterisk Gateway Interface (AGI)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'DeadAGI' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'EAGI' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'AGI' Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_functions.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_functions.so] => (Builtin dialplan functions) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function MD5 Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function CHECK_MD5 Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function MATH Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function GROUP_COUNT Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function GROUP_MATCH_COUNT Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function GROUP Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function GROUP_LIST Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function FIELDQTY Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function REGEX Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function LEN Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function STRFTIME Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function EVAL Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function CDR Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function ISNULL Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function SET Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function EXISTS Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function IF Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function IFTIME Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function ENV Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function DB Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function DB_EXISTS Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function TIMEOUT Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function LANGUAGE Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function MUSICCLASS Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_loopback.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_loopback.so] => (Loopback Switch) Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_ael.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_ael.so] => (Asterisk Extension Language Compiler) Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'macro-std-exten-ael' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'macro-std-exten-ael' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 1 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 1 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 2 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 2 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 3 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 3 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 4 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 4 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 5 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 5 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 'sw-4-BUSY' priority 1 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 'sw-4-BUSY' priority 2 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '_sw-4-.' priority 1 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '_sw-4-.' priority 1 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 'a' priority 1 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 'a' priority 1 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 'a' priority 2 to macro-std-exten-ael Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 'a' priority 2 to macro-std-exten-ael Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'ael-demo' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'ael-demo' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 3 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 3 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 4 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 4 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 5 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 5 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 6 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 6 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 8 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 8 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 9 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 9 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 10 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 10 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 11 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 11 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 12 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 12 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 7 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 7 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '2' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '2' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '2' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '2' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '3' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '3' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '3' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '3' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '500' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '500' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '500' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '500' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '500' priority 3 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '500' priority 3 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '500' priority 4 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '500' priority 4 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '600' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '600' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '600' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '600' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '600' priority 3 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '600' priority 3 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '600' priority 4 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '600' priority 4 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '_1234' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '_1234' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '#' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '#' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '#' priority 2 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '#' priority 2 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 't' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 't' priority 1 to ael-demo Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 'i' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 'i' priority 1 to ael-demo Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_realtime.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_realtime.so] => (Realtime Switch) Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_dundi.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_dundi.so] => (Distributed Universal Number Discovery (DUNDi)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/dundi.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/dundi.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/dundi.conf': Found Nov 6 10:13:36 DEBUG[17464] pbx_dundi.c: Seeding global EID '00:0c:6e:16:d5:3d' from 'eth0' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Using TOS bits 0 Nov 6 10:13:36 VERBOSE[17464] logger.c: == DUNDi Ready and Listening on 0.0.0.0 port 4520 Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'DUNDiLookup' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function DUNDILOOKUP Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_config.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_config.so] => (Text Extension Configuration) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/extensions.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/extensions.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/extensions.conf': Found Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'incoming' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'incoming' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 1 to incoming Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 1 to incoming Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 2 to incoming Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 2 to incoming Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '14037751733' priority 1 to incoming Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '14037751733' priority 1 to incoming Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '14037751733' priority 2 to incoming Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '14037751733' priority 2 to incoming Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '14037751733' priority 3 to incoming Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '14037751733' priority 3 to incoming Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'internal' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'internal' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '611' priority 1 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '611' priority 1 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '611' priority 2 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '611' priority 2 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '123' priority 1 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '123' priority 1 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '123' priority 2 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '123' priority 2 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '123' priority 3 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '123' priority 3 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '123' priority 4 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '123' priority 4 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '_1NXXNXXXXXX' priority 1 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '_1NXXNXXXXXX' priority 1 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '_1NXXNXXXXXX' priority 2 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '_1NXXNXXXXXX' priority 2 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '200' priority 1 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '200' priority 1 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '200' priority 2 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '200' priority 2 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension '200' priority 3 to internal Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension '200' priority 3 to internal Nov 6 10:13:36 DEBUG[17464] pbx.c: Registered context 'macro-page' Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Registered extension context 'macro-page' Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 1 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 1 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 2 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 2 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 3 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 3 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 4 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 4 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 5 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 5 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 6 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 6 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 7 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 7 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 8 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 8 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 9 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 9 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 10 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 10 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 11 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 11 to macro-page Nov 6 10:13:36 DEBUG[17464] pbx.c: Added extension 's' priority 12 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Added extension 's' priority 12 to macro-page Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_spool.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [pbx_spool.so] => (Outgoing Spool Support) Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_skinny.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_skinny.so] => (Skinny Client Control Protocol (Skinny)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/skinny.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/skinny.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/skinny.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: == Skinny listening on 0.0.0.0:2000 Nov 6 10:13:36 DEBUG[17464] channel.c: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_mgcp.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_mgcp.so] => (Media Gateway Control Protocol (MGCP)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/mgcp.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/mgcp.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/mgcp.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: == MGCP Listening on 0.0.0.0:2727 Nov 6 10:13:36 VERBOSE[17464] logger.c: == Using TOS bits 0 Nov 6 10:13:36 DEBUG[17464] channel.c: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_agent.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_agent.so] => (Agent Proxy Channel) Nov 6 10:13:36 DEBUG[17464] channel.c: Registered handler for 'Agent' (Call Agent Proxy Channel) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered channel type 'Agent' (Call Agent Proxy Channel) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'AgentLogin' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'AgentCallbackLogin' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'AgentMonitorOutgoing' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action Agents Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action AgentLogoff Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action AgentCallbackLogin Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/agents.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/agents.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/agents.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_zap.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_zap.so] => (Zapata Telephony) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/zapata.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/zapata.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/zapata.conf': Found Nov 6 10:13:36 VERBOSE[17464] logger.c: -- Automatically generated pseudo channel Nov 6 10:13:36 DEBUG[17464] channel.c: Registered handler for 'Zap' (Zapata Telephony Driver) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered channel type 'Zap' (Zapata Telephony Driver) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapTransfer Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapHangup Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapDialOffhook Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapDNDon Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapDNDoff Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action ZapShowChannels Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_oss.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_oss.so] => (OSS Console Channel Driver) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/oss.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/oss.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/oss.conf': Found Nov 6 10:13:36 DEBUG[17464] channel.c: Registered handler for 'Console' (OSS Console Channel Driver) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered channel type 'Console' (OSS Console Channel Driver) Nov 6 10:13:36 VERBOSE[17464] logger.c: [skipping chan_alsa.so] Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_iax2.so]Nov 6 10:13:36 VERBOSE[17464] logger.c: [chan_iax2.so] => (Inter Asterisk eXchange (Ver 2)) Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered custom function IAXPEER Nov 6 10:13:36 WARNING[17464] chan_iax2.c: Unable to open IAX timing interface: No such file or directory Nov 6 10:13:36 VERBOSE[17464] logger.c: == Registered application 'IAX2Provision' Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action IAXpeers Nov 6 10:13:36 VERBOSE[17464] logger.c: == Manager registered action IAXnetstats Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/iax.conf': Nov 6 10:13:36 DEBUG[17464] config.c: Parsing /etc/asterisk/iax.conf Nov 6 10:13:36 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/iax.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: == Using TOS bits 16 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Binding IAX2 to default address 0.0.0.0:4569 Nov 6 10:13:37 DEBUG[17464] channel.c: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == IAX Ready and Listening Nov 6 10:13:37 DEBUG[17464] chan_iax2.c: Allocate call number Nov 6 10:13:37 DEBUG[17464] chan_iax2.c: Registration created on call 1 Nov 6 10:13:37 DEBUG[17464] chan_iax2.c: Allocate call number Nov 6 10:13:37 DEBUG[17464] chan_iax2.c: Registration created on call 2 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Loaded firmware 'iaxy.bin' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/iaxprov.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/iaxprov.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/iaxprov.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- Loaded provisioning template 'default' Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_sip.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_sip.so] => (Session Initiation Protocol (SIP)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/sip.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/sip.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/sip.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- SIP Seeding peer from astdb: 'stephen' at stephen@10.130.1.10:5060 for 1800 Nov 6 10:13:37 VERBOSE[17464] logger.c: -- SIP Seeding peer from astdb: 'polycom' at polycom@10.130.1.100:5060 for 3600 Nov 6 10:13:37 VERBOSE[17464] logger.c: == SIP Listening on 0.0.0.0:5060 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Using TOS bits 0 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/sip_notify.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/sip_notify.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/sip_notify.conf': Found Nov 6 10:13:37 DEBUG[17464] channel.c: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SIPDtmfMode' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SIPAddHeader' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SIPGetHeader' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function SIP_HEADER Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function SIPPEER Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function SIPCHANINFO Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function CHECKSIPDOMAIN Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action SIPpeers Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action SIPshowpeer Nov 6 10:13:37 DEBUG[17464] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Nov 6 10:13:37 DEBUG[17464] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_local.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_local.so] => (Local Proxy Channel) Nov 6 10:13:37 DEBUG[17464] channel.c: Registered handler for 'Local' (Local Proxy Channel Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered channel type 'Local' (Local Proxy Channel Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_phone.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_phone.so] => (Linux Telephony API Support) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/phone.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/phone.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/phone.conf': Found Nov 6 10:13:37 DEBUG[17464] channel.c: Registered handler for 'Phone' (Standard Linux Telephony API Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered channel type 'Phone' (Standard Linux Telephony API Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_features.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [chan_features.so] => (Feature Proxy Channel) Nov 6 10:13:37 DEBUG[17464] channel.c: Registered handler for 'Feature' (Feature Proxy Channel Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered channel type 'Feature' (Feature Proxy Channel Driver) Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_directed_pickup.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_directed_pickup.so] => (Directed Call Pickup Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Pickup' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sayunixtime.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sayunixtime.so] => (Say time) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SayUnixTime' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DateTime' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_getcpeid.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_getcpeid.so] => (Get ADSI CPE ID) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'GetCPEID' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_waitforring.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_waitforring.so] => (Waits until first ring after time) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'WaitForRing' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_exec.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_exec.so] => (Executes applications) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Exec' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_txtcidname.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_txtcidname.so] => (TXTCIDName) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'TXTCIDName' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_eval.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_eval.so] => (Reevaluates strings) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Eval' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_festival.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_festival.so] => (Simple Festival Interface) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Festival' Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_adpcm.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_adpcm.so] => (Adaptive Differential PCM Coder/Decoder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_adpcm: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapras.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapras.so] => (Zap RAS Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ZapRAS' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_nbscat.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_nbscat.so] => (Silly NBS Stream Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'NBScat' Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_alaw.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_alaw.so] => (A-law Coder/Decoder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_alaw: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'alawtolin' from format alaw to slin, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintoalaw' from format slin to alaw, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_transfer.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_transfer.so] => (Transfer) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Transfer' Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_uri.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_uri.so] => (URI encode/decode functions) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function URIDECODE Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function URIENCODE Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_lpc10.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_lpc10.so] => (LPC10 2.4kbps (signed linear) Voice Coder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_lpc10: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 3 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintolpc10' from format slin to lpc10, cost 3 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_gsm.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_gsm.so] => (GSM/PCM16 (signed linear) Codec Translator) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_gsm: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'gsmtolin' from format gsm to slin, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17481] chan_sip.c: Stopping retransmission on '79e0ffdb6f9ca739528ffb4b7768df92@10.130.1.65' of Request 102: Match Found Nov 6 10:13:37 VERBOSE[17481] logger.c: -- Message count requested for mailbox 200@default but voicemail not loaded. Nov 6 10:13:37 DEBUG[17481] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintogsm' from format slin to gsm, cost 5 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17480] chan_iax2.c: Peer lastms 83, historicms 83, maxms 2000 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17480] chan_iax2.c: Peer lastms 86, historicms 86, maxms 2000 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapbarge.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapbarge.so] => (Barge in on Zap channel application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ZapBarge' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapscan.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapscan.so] => (Scan Zap channels application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ZapScan' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setrdnis.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setrdnis.so] => (Set RDNIS Number) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetRDNIS' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_jpeg.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_jpeg.so] => (JPEG (Joint Picture Experts Group) Image Format) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_queue.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_queue.so] => (True Call Queueing) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Queue' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action Queues Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action QueueStatus Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action QueueAdd Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action QueueRemove Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action QueuePause Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'AddQueueMember' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'RemoveQueueMember' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'PauseQueueMember' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'UnpauseQueueMember' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function QUEUEAGENTCOUNT Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/queues.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/queues.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/queues.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g729.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g729.so] => (Raw G729 data) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g729, extension(s) g729 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_controlplayback.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_controlplayback.so] => (Control Playback Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ControlPlayback' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g726.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g726.so] => (Raw G.726 (16/24/32/40kbps) data) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g726-40, extension(s) g726-40 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g726-32, extension(s) g726-32 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g726-24, extension(s) g726-24 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g726-16, extension(s) g726-16 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_image.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_image.so] => (Image Transmission Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SendImage' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_url.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_url.so] => (Send URL Applications) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SendURL' Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_ulaw.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_ulaw.so] => (Mu-law Coder/Decoder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_ulaw: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_ilbc.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_ilbc.so] => (iLBC/PCM16 (signed linear) Codec Translator) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'ilbctolin' from format ilbc to slin, cost 3 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17481] chan_sip.c: Stopping retransmission on '3a77183b4f59eb9610ff7fa03fe937ad@10.130.1.65' of Request 102: Match Found Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 VERBOSE[17480] logger.c: -- Registered IAX2 to 'xx.xx.xx.xx', who sees us as local_ip:port with no messages waiting Nov 6 10:13:37 VERBOSE[17480] logger.c: -- Registered IAX2 to 'xx.xx.xx.xx', who sees us as 66.18.218.36:58134 with no messages waiting Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintoilbc' from format slin to ilbc, cost 18 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 21 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_lookupblacklist.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_lookupblacklist.so] => (Look up Caller*ID name/number from blacklist database) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'LookupBlacklist' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcdruserfield.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcdruserfield.so] => (CDR user field apps) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetCDRUserField' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'AppendCDRUserField' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Manager registered action SetCDRUserField Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_alarmreceiver.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_alarmreceiver.so] => (Alarm Receiver for Asterisk) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/alarmreceiver.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/alarmreceiver.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/alarmreceiver.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'AlarmReceiver' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_math.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_math.so] => (Basic Math Functions) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Math' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_macro.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_macro.so] => (Extension Macros) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MacroExit' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MacroIf' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Macro' Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_custom.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_custom.so] => (Customizable Comma Separated Values CDR Backend) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_custom.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/cdr_custom.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_custom.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapateller.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_zapateller.so] => (Block Telemarketers with Special Information Tone) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Zapateller' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_stack.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_stack.so] => (Stack Routines) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'StackPop' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Return' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'GosubIf' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Gosub' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_cdr.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_cdr.so] => (Tell Asterisk to not maintain a CDR for the current call) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'NoCDR' Nov 6 10:13:37 VERBOSE[17464] logger.c: [skipping chan_alsa.so] Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_lookupcidname.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_lookupcidname.so] => (Look up CallerID Name from local database) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'LookupCIDName' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_pcm_alaw.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_pcm_alaw.so] => (Raw aLaw 8khz PCM Audio support) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format alaw, extension(s) alaw|al Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_verbose.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_verbose.so] => (Send verbose output) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Verbose' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_read.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_read.so] => (Read Variable Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Read' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_disa.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_disa.so] => (DISA (Direct Inward System Access) Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DISA' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_privacy.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_privacy.so] => (Require phone number to be entered, if no CallerID sent) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'PrivacyManager' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_senddtmf.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_senddtmf.so] => (Send DTMF digits Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SendDTMF' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_curl.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_curl.so] => (Load external URL) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function CURL Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Curl' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_while.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_while.so] => (While Loops and Conditional Execution) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'While' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ExecIf' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'EndWhile' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_directory.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_directory.so] => (Extension Directory) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Directory' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_wav_gsm.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_wav_gsm.so] => (Microsoft WAV format (Proprietary GSM)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format wav49, extension(s) WAV|wav49 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_hasnewvoicemail.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_hasnewvoicemail.so] => (Indicator for whether a voice mailbox has messages in a given folder. messages in a given folder.[) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function VMCOUNT Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'HasVoicemail' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'HasNewVoicemail' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_milliwatt.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_milliwatt.so] => (Digital Milliwatt (mu-law) Test Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Milliwatt' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_echo.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_echo.so] => (Simple Echo Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Echo' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_mixmonitor.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_mixmonitor.so] => (Mixed Audio Monitoring Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MixMonitor' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_test.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_test.so] => (Interface Test Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'TestClient' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'TestServer' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_chanisavail.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_chanisavail.so] => (Check channel availability) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ChanIsAvail' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_userevent.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_userevent.so] => (Custom User Event Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'UserEvent' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_flash.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_flash.so] => (Flash zap trunk application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Flash' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcallerid.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcallerid.so] => (Set CallerID Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetCallerPres' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetCallerID' Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_csv.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_csv.so] => (Comma Separated Values CDR Backend) Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_pgsql.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_pgsql.so] => (PostgreSQL CDR Backend) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_pgsql.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/cdr_pgsql.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_pgsql.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_groupcount.so]Nov 6 10:13:37 DEBUG[17481] chan_sip.c: Stopping retransmission on '7bb5e49725c5743105721f713e4dba72@10.130.1.65' of Request 102: Match Found Nov 6 10:13:37 DEBUG[17483] app_queue.c: Device 'SIP/stephen' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_groupcount.so] => (Group Management Routines) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'GetGroupCount' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetGroup' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'CheckGroup' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'GetGroupMatchCount' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_settransfercapability.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_settransfercapability.so] => (Set ISDN Transfer Capability) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetTransferCapability' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_adsiprog.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_adsiprog.so] => (Asterisk ADSI Programming Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ADSIProg' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_ogg_vorbis.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_ogg_vorbis.so] => (OGG/Vorbis audio) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format ogg_vorbis, extension(s) ogg Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_cut.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_cut.so] => (Cut out information from a string) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function CUT Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function SORT Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Sort' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Cut' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_md5.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_md5.so] => (MD5 checksum applications) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MD5Check' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MD5' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_pcm.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_pcm.so] => (Raw uLaw 8khz Audio support (PCM)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format pcm, extension(s) pcm|ulaw|ul|mu Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_enum.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_enum.so] => (ENUM Related Functions) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function ENUMLOOKUP Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function TXTCIDNAME Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_au.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_au.so] => (Sun Microsystems AU format (signed linear)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format au, extension(s) au Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g723.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_g723.so] => (G.723.1 Simple Timestamp File Format) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format g723sf, extension(s) g723|g723sf Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_realtime.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_realtime.so] => (Realtime Data Lookup/Rewrite) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'RealTimeUpdate' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'RealTime' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_system.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_system.so] => (Generic System() application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'TrySystem' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'System' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_wav.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_wav.so] => (Microsoft WAV format (8000hz Signed Linear)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format wav, extension(s) wav Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_random.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_random.so] => (Random goto) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Random' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sendtext.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sendtext.so] => (Send Text Applications) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SendText' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_voicemail.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_voicemail.so] => (Comedian Mail (Voicemail System)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'VoiceMail' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'VoiceMailMain' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MailboxExists' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'VMAuthenticate' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/voicemail.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/voicemail.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/voicemail.conf': Found Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: VM Review Option disabled globally Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: VM Operator break disabled globally Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: VM CID Info before msg disabled globally Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: ENVELOPE before msg enabled globally Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: Duration info before msg enabled globally Nov 6 10:13:37 DEBUG[17464] app_voicemail.c: We are not going to skip to the next msg after save/delete Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_talkdetect.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_talkdetect.so] => (Playback with Talk Detection) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'BackgroundDetect' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_waitforsilence.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_waitforsilence.so] => (Wait For Silence) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'WaitForSilence' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_chanspy.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_chanspy.so] => (Listen to the audio of an active channel ) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ChanSpy' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_externalivr.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_externalivr.so] => (External IVR Interface Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ExternalIVR' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_mp3.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_mp3.so] => (Silly MP3 Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MP3Player' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dumpchan.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dumpchan.so] => (Dump Info About The Calling Channel) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DumpChan' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_page.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_page.so] => (Page Multiple Phones) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Page' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_readfile.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_readfile.so] => (Stores output of file into a variable) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ReadFile' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_gsm.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_gsm.so] => (Raw GSM data) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format gsm, extension(s) gsm Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_authenticate.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_authenticate.so] => (Authentication Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Authenticate' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcidnum.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcidnum.so] => (Set CallerID Number) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetCIDNum' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_meetme.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_meetme.so] => (MeetMe conference bridge) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/meetme.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/meetme.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/meetme.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MeetMeAdmin' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MeetMeCount' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'MeetMe' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sms.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_sms.so] => (SMS/PSTN handler) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SMS' Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_g726.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_g726.so] => (ITU G.726-32kbps G726 Transcoder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/codecs.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/codecs.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: -- codec_g726: using generic PLC Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'g726tolin' from format g726 to slin, cost 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from ulaw to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 21 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'lintog726' from format slin to g726, cost 3 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from ulaw to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 21 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_record.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_record.so] => (Trivial Record Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Record' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_ices.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_ices.so] => (Encode and Stream via icecast and ices) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ICES' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_playback.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_playback.so] => (Sound File Playback Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Playback' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_parkandannounce.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_parkandannounce.so] => (Call Parking and Announce Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ParkAndAnnounce' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_softhangup.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_softhangup.so] => (Hangs up the requested channel) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SoftHangup' Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_manager.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [cdr_manager.so] => (Asterisk Call Manager CDR Backend) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_manager.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/cdr_manager.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/cdr_manager.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_sln.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_sln.so] => (Raw Signed Linear Audio support (SLN)) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format sln, extension(s) sln|raw Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dictate.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dictate.so] => (Virtual Dictation Machine) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Dictate' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_forkcdr.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_forkcdr.so] => (Fork The CDR into 2 separate entities.) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'ForkCDR' Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcidname.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_setcidname.so] => (Set CallerID Name) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'SetCIDName' Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_callerid.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [func_callerid.so] => (Caller ID related dialplan function) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered custom function CALLERID Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_h263.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_h263.so] => (Raw h263 data) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format h263, extension(s) h263 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dial.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_dial.so] => (Dialing Application) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'Dial' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'RetryDial' Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_vox.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_vox.so] => (Dialogic VOX (ADPCM) File Format) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format vox, extension(s) vox Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_enumlookup.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_enumlookup.so] => (ENUM Lookup) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'EnumLookup' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/enum.conf': Nov 6 10:13:37 DEBUG[17464] config.c: Parsing /etc/asterisk/enum.conf Nov 6 10:13:37 VERBOSE[17464] logger.c: == Parsing '/etc/asterisk/enum.conf': Found Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_ilbc.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [format_ilbc.so] => (Raw iLBC data) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered file format iLBC, extension(s) ilbc Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_a_mu.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [codec_a_mu.so] => (A-law and Mulaw direct Coder/Decoder) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from unknown to g723, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from unknown to ulaw, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from ulaw to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 21 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 Nov 6 10:13:37 DEBUG[17464] translate.c: Resetting translation matrix Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from gsm to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from gsm to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from unknown to g723, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from unknown to ulaw, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from unknown to unknown, via 2 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 7 cost path from ulaw to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 3 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 5 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 20 cost path from ulaw to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 21 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 8 cost path from unknown to g723, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to ulaw, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Nov 6 10:13:37 DEBUG[17464] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_db.so]Nov 6 10:13:37 VERBOSE[17464] logger.c: [app_db.so] => (Database Access Functions) Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DBget' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DBput' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DBdel' Nov 6 10:13:37 VERBOSE[17464] logger.c: == Registered application 'DBdeltree' Nov 6 10:13:37 VERBOSE[17464] logger.c: Asterisk Ready. Nov 6 10:13:48 DEBUG[17481] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Nov 6 10:13:48 DEBUG[17481] chan_sip.c: Stopping retransmission on '489f04813756fa4822fd318d42ae3350@10.130.1.65' of Request 102: Match Found Nov 6 10:14:09 DEBUG[17481] chan_sip.c: Allocating new SIP dialog for 39e880f0-7c3a473a-5d9b44b@10.130.1.100 - INVITE (With RTP) Nov 6 10:14:09 DEBUG[17481] chan_sip.c: * SIP extension value: 2 for call 39e880f0-7c3a473a-5d9b44b@10.130.1.100 Nov 6 10:14:09 DEBUG[17481] chan_sip.c: Setting NAT on RTP to 0 Nov 6 10:14:09 DEBUG[17481] chan_sip.c: Stopping retransmission on '39e880f0-7c3a473a-5d9b44b@10.130.1.100' of Response 1: Match Found Nov 6 10:14:09 DEBUG[17481] chan_sip.c: Setting NAT on RTP to 0 Nov 6 10:14:09 DEBUG[17481] chan_sip.c: Checking SIP call limits for device polycom Nov 6 10:14:09 DEBUG[17481] chan_sip.c: build_route: Contact hop: Nov 6 10:14:10 DEBUG[17484] pbx.c: Launching 'Answer' Nov 6 10:14:10 VERBOSE[17484] logger.c: Asterisk Ready. -- Executing Answer("SIP/polycom-0817d070", "") in new stack Nov 6 10:14:10 DEBUG[17484] chan_sip.c: sip_answer(SIP/polycom-0817d070) Nov 6 10:14:10 DEBUG[17467] channel.c: Avoiding initial deadlock for 'SIP/polycom-0817d070' Nov 6 10:14:10 DEBUG[17485] app_queue.c: Device 'SIP/polycom' changed to state '2' (In use) but we don't care because they're not a member of any queue. Nov 6 10:14:10 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:10 VERBOSE[17484] logger.c: -- Executing Wait("SIP/polycom-0817d070", "1") in new stack Nov 6 10:14:10 DEBUG[17486] app_queue.c: Device 'SIP/polycom' changed to state '2' (In use) but we don't care because they're not a member of any queue. Nov 6 10:14:10 DEBUG[17481] chan_sip.c: Stopping retransmission on '39e880f0-7c3a473a-5d9b44b@10.130.1.100' of Response 2: Match Found Nov 6 10:14:11 DEBUG[17484] pbx.c: Launching 'Dial' Nov 6 10:14:11 VERBOSE[17484] logger.c: -- Executing Dial("SIP/polycom-0817d070", "IAX2/vodacomm@FreeWorldTel-Out2/[called_party_telephone_number]||M(page)") in new stack Nov 6 10:14:11 DEBUG[17484] chan_iax2.c: New max nontrunk callno is 6 Nov 6 10:14:11 DEBUG[17484] chan_iax2.c: Creating new call structure 5 Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable STACK-internal-123-3. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable STACK-internal-123-2. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable STACK-internal-123-1. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable SIPCALLID. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable SIPUSERAGENT. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable SIPDOMAIN. Nov 6 10:14:11 DEBUG[17484] channel.c: Not copying variable SIPURI. Nov 6 10:14:11 VERBOSE[17484] logger.c: -- Called vodacomm@FreeWorldTel-Out2/[called_party_telephone_number] Nov 6 10:14:11 DEBUG[17484] channel.c: Set channel IAX2/FreeWorldTel-Out2-5 to read format ulaw Nov 6 10:14:11 DEBUG[17484] channel.c: Set channel IAX2/FreeWorldTel-Out2-5 to write format ulaw Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00015ms SCall: 00005 DCall: 00000 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: VERSION : 2 Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLED NUMBER : [called_party_telephone_number] Nov 6 10:14:11 VERBOSE[17480] logger.c: CODEC_PREFS : (ulaw) Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLING NUMBER : polycom Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLING PRESNTN : 0 Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLING TYPEOFN : 0 Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLING TRANSIT : 0 Nov 6 10:14:11 VERBOSE[17480] logger.c: CALLING NAME : polycom Nov 6 10:14:11 VERBOSE[17480] logger.c: LANGUAGE : en Nov 6 10:14:11 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:11 VERBOSE[17480] logger.c: FORMAT : 4 Nov 6 10:14:11 VERBOSE[17480] logger.c: CAPABILITY : 63492 Nov 6 10:14:11 VERBOSE[17480] logger.c: ADSICPE : 2 Nov 6 10:14:11 VERBOSE[17480] logger.c: DATE TIME : 2006-11-06 10:14:10 Nov 6 10:14:11 VERBOSE[17480] logger.c: Nov 6 10:14:11 DEBUG[17487] app_queue.c: Device 'IAX2/FreeWorldTel-Out2' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. Nov 6 10:14:11 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00013ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: AUTHMETHODS : 2 Nov 6 10:14:11 VERBOSE[17480] logger.c: CHALLENGE : 198867275 Nov 6 10:14:11 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:11 VERBOSE[17480] logger.c: Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Received packet 0, (6, 8) Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 0 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: IAX subclass 8 received Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=13 Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00088ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: MD5 RESULT : 236dcb279efcc4e2cd464cf539e14537 Nov 6 10:14:11 VERBOSE[17480] logger.c: Nov 6 10:14:11 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00084ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: FORMAT : 4 Nov 6 10:14:11 VERBOSE[17480] logger.c: Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Received packet 1, (6, 7) Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 1 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: IAX subclass 7 received Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=84 Nov 6 10:14:11 VERBOSE[17480] logger.c: -- Call accepted by xx.xx.xx.xx (format ulaw) Nov 6 10:14:11 VERBOSE[17480] logger.c: -- Format for call is ulaw Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00084ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 002 Type: VOICE Subclass: 4 Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00150ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:11 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00150ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Received packet 2, (6, 4) Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 2 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:11 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: CONTROL Subclass: (14?) Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00255ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Received packet 2, (4, 14) Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00255ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=255 Nov 6 10:14:11 VERBOSE[17484] logger.c: -- IAX2/FreeWorldTel-Out2-5 is making progress passing it to SIP/polycom-0817d070 Nov 6 10:14:11 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: VOICE Subclass: 4 Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00360ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Received packet 3, (2, 4) Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: Ooh, voice format changed to 4 Nov 6 10:14:11 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 004 Type: IAX Subclass: ACK Nov 6 10:14:11 VERBOSE[17480] logger.c: Timestamp: 00360ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=360 Nov 6 10:14:11 DEBUG[17484] rtp.c: Ooh, format changed from unknown to ulaw Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=390 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=420 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=450 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=480 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=510 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=540 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=570 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=600 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=630 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=660 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=690 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=720 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=750 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=780 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=810 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=840 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=870 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=900 Nov 6 10:14:11 DEBUG[17480] chan_iax2.c: For call=5, set last=930 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=960 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=990 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1020 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1050 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1080 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1110 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1140 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1170 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1200 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1230 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1260 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1290 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1320 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1350 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1380 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1410 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1440 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1470 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1500 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1530 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1560 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1590 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1620 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1650 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1680 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1710 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1740 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1770 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1800 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1830 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1860 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1890 Nov 6 10:14:12 DEBUG[17480] chan_iax2.c: For call=5, set last=1920 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=1950 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=1980 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2010 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2040 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2070 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2100 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2130 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2160 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2190 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2220 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2250 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2280 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2310 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2340 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2370 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2400 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2430 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2460 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2490 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2520 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2550 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2580 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2610 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2640 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2670 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2700 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2730 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2760 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2790 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2820 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2850 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2880 Nov 6 10:14:13 DEBUG[17480] chan_iax2.c: For call=5, set last=2910 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=2940 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=2970 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3000 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3030 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3060 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3090 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3120 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3150 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3180 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3210 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3240 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3270 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3300 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3330 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3360 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3390 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3420 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3450 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3480 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3510 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3540 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3570 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3600 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3630 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3660 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3690 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3720 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3750 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3780 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3810 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3840 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3870 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3900 Nov 6 10:14:14 DEBUG[17480] chan_iax2.c: For call=5, set last=3930 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=3960 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=3990 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4020 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4050 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4080 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4110 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4140 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4170 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4200 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4230 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4260 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4290 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4320 Nov 6 10:14:15 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: CONTROL Subclass: ANSWER Nov 6 10:14:15 VERBOSE[17480] logger.c: Timestamp: 04323ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: Received packet 4, (4, 4) Nov 6 10:14:15 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK Nov 6 10:14:15 VERBOSE[17480] logger.c: Timestamp: 04323ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4323 Nov 6 10:14:15 VERBOSE[17484] logger.c: -- IAX2/FreeWorldTel-Out2-5 answered SIP/polycom-0817d070 Nov 6 10:14:15 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:15 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:15 DEBUG[17489] app_queue.c: Device 'IAX2/FreeWorldTel-Out2' changed to state '2' (In use) but we don't care because they're not a member of any queue. Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4350 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4380 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4410 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4440 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4470 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4500 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4530 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4560 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4590 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4620 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4650 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4680 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4710 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4740 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4770 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4800 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4830 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4860 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4890 Nov 6 10:14:15 DEBUG[17480] chan_iax2.c: For call=5, set last=4920 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=4950 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=4980 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5010 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5040 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5070 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5100 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5130 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5160 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5190 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5220 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5250 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5280 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5310 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5340 Nov 6 10:14:16 DEBUG[17484] pbx.c: Launching 'SendDTMF' Nov 6 10:14:16 VERBOSE[17484] logger.c: -- Executing SendDTMF("IAX2/FreeWorldTel-Out2-5", "234|100") in new stack Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5370 Nov 6 10:14:16 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 2 Nov 6 10:14:16 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 005 Type: DTMF Subclass: 2 Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05421ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5400 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5430 Nov 6 10:14:16 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: ACK Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05421ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 3 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5460 Nov 6 10:14:16 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 3 Nov 6 10:14:16 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 005 Type: DTMF Subclass: 3 Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05424ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5490 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5520 Nov 6 10:14:16 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX Subclass: ACK Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05424ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 4 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:16 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 4 Nov 6 10:14:16 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 005 Type: DTMF Subclass: 4 Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05637ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5550 Nov 6 10:14:16 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 006 Type: IAX Subclass: ACK Nov 6 10:14:16 VERBOSE[17480] logger.c: Timestamp: 05637ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 5 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:16 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:16 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:16 DEBUG[17488] rtp.c: Got RTCP report of 88 bytes Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5790 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5820 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5850 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5880 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5910 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5940 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=5970 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=6000 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=6030 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=6060 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: For call=5, set last=6090 Nov 6 10:14:16 DEBUG[17480] chan_iax2.c: Received out of order packet... (type=2, subclass 4, ts = 5970, last = 6090) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Received out of order packet... (type=2, subclass 4, ts = 6000, last = 6090) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Received out of order packet... (type=2, subclass 4, ts = 6030, last = 6090) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Received out of order packet... (type=2, subclass 4, ts = 6060, last = 6090) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Received out of order packet... (type=2, subclass 4, ts = 6090, last = 6090) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6120 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6150 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6180 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6210 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6240 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6270 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6300 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6330 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6360 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6390 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6420 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6450 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6480 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6510 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6540 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6570 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6600 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6630 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6660 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6690 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6720 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6750 Nov 6 10:14:17 DEBUG[17484] pbx.c: Launching 'SendDTMF' Nov 6 10:14:17 VERBOSE[17484] logger.c: -- Executing SendDTMF("IAX2/FreeWorldTel-Out2-5", "6|250") in new stack Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6780 Nov 6 10:14:17 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 6 Nov 6 10:14:17 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 005 Type: DTMF Subclass: 6 Nov 6 10:14:17 VERBOSE[17480] logger.c: Timestamp: 06763ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6810 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6840 Nov 6 10:14:17 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 007 Type: IAX Subclass: ACK Nov 6 10:14:17 VERBOSE[17480] logger.c: Timestamp: 06763ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 6 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6870 Nov 6 10:14:17 DEBUG[17480] chan_iax2.c: For call=5, set last=6900 Nov 6 10:14:18 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:18 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=6930 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=6960 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=6990 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7020 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7050 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7080 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7110 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7140 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7170 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7200 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7230 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7260 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7290 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7320 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7350 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7380 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7410 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7440 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7470 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7500 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7530 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7560 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7590 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7620 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7650 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7680 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7710 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7740 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7770 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7800 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7830 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7860 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7890 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7920 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7950 Nov 6 10:14:18 DEBUG[17480] chan_iax2.c: For call=5, set last=7980 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8010 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8040 Nov 6 10:14:19 DEBUG[17484] pbx.c: Launching 'SendDTMF' Nov 6 10:14:19 VERBOSE[17484] logger.c: -- Executing SendDTMF("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8070 Nov 6 10:14:19 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 1 Nov 6 10:14:19 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 005 Type: DTMF Subclass: 1 Nov 6 10:14:19 VERBOSE[17480] logger.c: Timestamp: 08057ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8100 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8130 Nov 6 10:14:19 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 008 Type: IAX Subclass: ACK Nov 6 10:14:19 VERBOSE[17480] logger.c: Timestamp: 08057ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 7 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8160 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8190 Nov 6 10:14:19 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:19 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8220 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8250 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8280 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8310 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8340 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8370 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8400 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8430 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8460 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8490 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8520 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8550 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8580 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8610 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8640 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8670 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8700 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8730 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8760 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8790 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8820 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8850 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8880 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8910 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8940 Nov 6 10:14:19 DEBUG[17480] chan_iax2.c: For call=5, set last=8970 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9000 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9030 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9060 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9090 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9120 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9150 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9180 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9210 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9240 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9270 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9300 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9330 Nov 6 10:14:20 DEBUG[17484] pbx.c: Launching 'SendDTMF' Nov 6 10:14:20 VERBOSE[17484] logger.c: -- Executing SendDTMF("IAX2/FreeWorldTel-Out2-5", "3") in new stack Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9360 Nov 6 10:14:20 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 3 Nov 6 10:14:20 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 005 Type: DTMF Subclass: 3 Nov 6 10:14:20 VERBOSE[17480] logger.c: Timestamp: 09346ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9390 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9420 Nov 6 10:14:20 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 009 Type: IAX Subclass: ACK Nov 6 10:14:20 VERBOSE[17480] logger.c: Timestamp: 09346ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 4) Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 8 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9450 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9480 Nov 6 10:14:20 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:20 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "1") in new stack Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9510 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9540 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9570 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9600 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9630 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9660 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9690 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9720 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9750 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9780 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9810 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9840 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9870 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9900 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9930 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9960 Nov 6 10:14:20 DEBUG[17480] chan_iax2.c: For call=5, set last=9990 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10020 Nov 6 10:14:21 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 005 Type: IAX Subclass: LAGRQ Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10021ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10050 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10080 Nov 6 10:14:21 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 009 Type: IAX Subclass: LAGRQ Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10097ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Received packet 5, (6, 11) Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: IAX subclass 11 received Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10097 Nov 6 10:14:21 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 006 Type: IAX Subclass: LAGRP Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10097ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:21 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 010 Type: IAX Subclass: LAGRP Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10021ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Received packet 6, (6, 12) Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 9 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: IAX subclass 12 received Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Peer xx.xx.xx.xx lag measured as 73ms Nov 6 10:14:21 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 010 ISeqno: 007 Type: IAX Subclass: ACK Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10021ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10110 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10140 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10170 Nov 6 10:14:21 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 011 Type: IAX Subclass: ACK Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10097ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Received packet 6, (6, 4) Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 10 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10200 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10230 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10260 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10290 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10320 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10350 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10380 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10410 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10440 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10470 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10500 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10530 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10560 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10590 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10620 Nov 6 10:14:21 DEBUG[17484] pbx.c: Launching 'SendDTMF' Nov 6 10:14:21 VERBOSE[17484] logger.c: -- Executing SendDTMF("IAX2/FreeWorldTel-Out2-5", "6") in new stack Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10650 Nov 6 10:14:21 DTMF[17484] channel.c: IAX2/FreeWorldTel-Out2-5 : 6 Nov 6 10:14:21 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 007 Type: DTMF Subclass: 6 Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10640ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10680 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10710 Nov 6 10:14:21 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 012 Type: IAX Subclass: ACK Nov 6 10:14:21 VERBOSE[17480] logger.c: Timestamp: 10640ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Received packet 7, (6, 4) Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 11 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10740 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10770 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10800 Nov 6 10:14:21 DEBUG[17484] pbx.c: Launching 'Wait' Nov 6 10:14:21 VERBOSE[17484] logger.c: -- Executing Wait("IAX2/FreeWorldTel-Out2-5", "10") in new stack Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10830 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10860 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10890 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10920 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10950 Nov 6 10:14:21 DEBUG[17480] chan_iax2.c: For call=5, set last=10980 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11010 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11040 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11070 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11100 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11130 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11160 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11190 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11220 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11250 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11280 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11310 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11340 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11370 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11400 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11430 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11460 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11490 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11520 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11550 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11580 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11610 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11640 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11670 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11700 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11730 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11760 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11790 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11820 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11850 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11880 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11910 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11940 Nov 6 10:14:22 DEBUG[17480] chan_iax2.c: For call=5, set last=11970 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12000 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12030 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12060 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12090 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12120 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12150 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12180 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12210 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12240 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12270 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12300 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12330 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12360 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12390 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12420 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12450 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12480 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12510 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12540 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12570 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12600 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12630 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12660 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12690 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12720 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12750 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12780 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12810 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12840 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12870 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12900 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12930 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12960 Nov 6 10:14:23 DEBUG[17480] chan_iax2.c: For call=5, set last=12990 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13020 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13050 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13080 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13110 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13140 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13170 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13200 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13230 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13260 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13290 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13320 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13350 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13380 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13410 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13440 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13470 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13500 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13530 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13560 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13590 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13620 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13650 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13680 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13710 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13740 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13770 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13800 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13830 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13860 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13890 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13920 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13950 Nov 6 10:14:24 DEBUG[17480] chan_iax2.c: For call=5, set last=13980 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14010 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14040 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14070 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14100 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14130 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14160 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14190 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14220 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14250 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14280 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14310 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14340 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14370 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14400 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14430 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14460 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14490 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14520 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14550 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14580 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14610 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14640 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14670 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14700 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14730 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14760 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14790 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14820 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14850 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14880 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14910 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14940 Nov 6 10:14:25 DEBUG[17480] chan_iax2.c: For call=5, set last=14970 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15000 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15030 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15060 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15090 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15120 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15150 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15180 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15210 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15240 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15270 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15300 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15330 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15360 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15390 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15420 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15450 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15480 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15510 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15540 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15570 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15600 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15630 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15660 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15690 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15720 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15750 Nov 6 10:14:26 DEBUG[17488] rtp.c: Got RTCP report of 88 bytes Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15780 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15810 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15840 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15870 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15900 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15930 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15960 Nov 6 10:14:26 DEBUG[17480] chan_iax2.c: For call=5, set last=15990 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16020 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16050 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16080 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16110 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16140 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16170 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16200 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Sending registration request for 'vodacomm' Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Allocate call number Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: New max nontrunk callno is 7 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Creating new call structure 6 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Registration created on call 6 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Sending registration request for 'vodacomm' Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Allocate call number Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: New max nontrunk callno is 8 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Creating new call structure 7 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Registration created on call 7 Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00014ms SCall: 00006 DCall: 00000 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00014ms SCall: 00007 DCall: 00000 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16230 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16260 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16290 Nov 6 10:14:27 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00011ms SCall: 00205 DCall: 00006 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: AUTHMETHODS : 2 Nov 6 10:14:27 VERBOSE[17480] logger.c: CHALLENGE : 206436631 Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Received packet 0, (6, 14) Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 0 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: IAX subclass 14 received Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=6, set last=11 Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00087ms SCall: 00006 DCall: 00205 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: MD5 RESULT : e8c95222fe4e127e5b17ec251a4de08e Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00010ms SCall: 00097 DCall: 00007 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: AUTHMETHODS : 2 Nov 6 10:14:27 VERBOSE[17480] logger.c: CHALLENGE : 468924222 Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Received packet 0, (6, 14) Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 0 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: IAX subclass 14 received Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=7, set last=10 Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00088ms SCall: 00007 DCall: 00097 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: MD5 RESULT : 67c6f3098b8586ca988076ca113f91b2 Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16320 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16350 Nov 6 10:14:27 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00086ms SCall: 00205 DCall: 00006 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: DATE TIME : 2006-11-06 17:14:26 Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: APPARENT ADDRES : IPV4 local_ip:port Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Received packet 1, (6, 15) Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 1 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: IAX subclass 15 received Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=6, set last=86 Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00086ms SCall: 00006 DCall: 00205 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00086ms SCall: 00097 DCall: 00007 [xx.xx.xx.xx:4569] Nov 6 10:14:27 VERBOSE[17480] logger.c: USERNAME : vodacomm Nov 6 10:14:27 VERBOSE[17480] logger.c: DATE TIME : 2006-11-06 17:14:26 Nov 6 10:14:27 VERBOSE[17480] logger.c: REFRESH : 60 Nov 6 10:14:27 VERBOSE[17480] logger.c: APPARENT ADDRES : IPV4 66.18.218.36:58134 Nov 6 10:14:27 VERBOSE[17480] logger.c: Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Received packet 1, (6, 15) Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 1 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: IAX subclass 15 received Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=7, set last=86 Nov 6 10:14:27 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Nov 6 10:14:27 VERBOSE[17480] logger.c: Timestamp: 00086ms SCall: 00007 DCall: 00097 [xx.xx.xx.xx:4569] Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16380 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16410 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16440 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16470 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16500 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16530 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16560 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16590 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16620 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16650 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16680 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16710 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16740 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16770 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16800 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16830 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16860 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16890 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16920 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16950 Nov 6 10:14:27 DEBUG[17480] chan_iax2.c: For call=5, set last=16980 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17010 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17040 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17070 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17100 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17130 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17160 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17190 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17220 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17250 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17280 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17310 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17340 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17370 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17400 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17430 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17460 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17490 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17520 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17550 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17580 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17610 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17640 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17670 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17700 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17730 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17760 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17790 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17820 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17850 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17880 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17910 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17940 Nov 6 10:14:28 DEBUG[17480] chan_iax2.c: For call=5, set last=17970 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18000 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18030 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18060 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18090 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18120 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18150 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18180 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18210 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18240 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18270 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18300 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18330 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18360 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18390 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18420 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18450 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18480 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18510 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18540 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18570 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18600 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18630 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18660 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18690 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18720 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18750 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18780 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18810 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18840 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18870 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18900 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18930 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18960 Nov 6 10:14:29 DEBUG[17480] chan_iax2.c: For call=5, set last=18990 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19020 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19050 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19080 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19110 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19140 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19170 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19200 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19230 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19260 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19290 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19320 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19350 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19380 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19410 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19440 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19470 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19500 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19530 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19560 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19590 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19620 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19650 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19680 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19710 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19740 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19770 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19800 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19830 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19860 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19890 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19920 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19950 Nov 6 10:14:30 DEBUG[17480] chan_iax2.c: For call=5, set last=19980 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20010 Nov 6 10:14:31 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 007 Type: IAX Subclass: PING Nov 6 10:14:31 VERBOSE[17480] logger.c: Timestamp: 20017ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:31 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 013 ISeqno: 007 Type: IAX Subclass: LAGRQ Nov 6 10:14:31 VERBOSE[17480] logger.c: Timestamp: 20020ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20040 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20070 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20100 Nov 6 10:14:31 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 012 Type: IAX Subclass: PING Nov 6 10:14:31 VERBOSE[17480] logger.c: Timestamp: 20104ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: Received packet 7, (6, 2) Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: IAX subclass 2 received Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20104 Nov 6 10:14:31 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 014 ISeqno: 008 Type: IAX Subclass: PONG Nov 6 10:14:31 VERBOSE[17480] logger.c: Timestamp: 20104ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_JITTER : 0 Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_LOSS : 0 Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_PKTS : 1 Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_DELAY : 40 Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_DROPPED : 0 Nov 6 10:14:31 VERBOSE[17480] logger.c: RR_OUTOFORDER : 0 Nov 6 10:14:31 VERBOSE[17480] logger.c: Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20130 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20160 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20190 Nov 6 10:14:31 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 015 Type: IAX Subclass: ACK Nov 6 10:14:31 VERBOSE[17480] logger.c: Timestamp: 20104ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: Received packet 8, (6, 4) Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 12 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 13 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 14 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: IAX subclass 4 received Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20220 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20250 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20280 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20310 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20340 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20370 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20400 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20430 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20460 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20490 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20520 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20550 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20580 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20610 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20640 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20670 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20700 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20730 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20760 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20790 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20820 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20850 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20880 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20910 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20940 Nov 6 10:14:31 DEBUG[17480] chan_iax2.c: For call=5, set last=20970 Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21000 Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21030 Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21060 Nov 6 10:14:32 DEBUG[17484] pbx.c: Launching 'Set' Nov 6 10:14:32 VERBOSE[17484] logger.c: -- Executing Set("IAX2/FreeWorldTel-Out2-5", "MACRO_RESULT=CONTINUE") in new stack Nov 6 10:14:32 DEBUG[17484] app_dial.c: Macro exited with status 0 Nov 6 10:14:32 DEBUG[17484] channel.c: Hanging up channel 'IAX2/FreeWorldTel-Out2-5' Nov 6 10:14:32 DEBUG[17484] chan_iax2.c: We're hanging up IAX2/FreeWorldTel-Out2-5 now... Nov 6 10:14:32 VERBOSE[17484] logger.c: -- Hungup 'IAX2/FreeWorldTel-Out2-5' Nov 6 10:14:32 DEBUG[17484] app_dial.c: Exiting with DIALSTATUS=ANSWER. Nov 6 10:14:32 DEBUG[17484] pbx.c: Launching 'Hangup' Nov 6 10:14:32 VERBOSE[17484] logger.c: -- Executing Hangup("SIP/polycom-0817d070", "") in new stack Nov 6 10:14:32 DEBUG[17484] pbx.c: Spawn extension (internal,123,4) exited non-zero on 'SIP/polycom-0817d070' Nov 6 10:14:32 VERBOSE[17484] logger.c: == Spawn extension (internal, 123, 4) exited non-zero on 'SIP/polycom-0817d070' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '"polycom" ' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'polycom' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '123' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'internal' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'SIP/polycom-0817d070' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'IAX2/FreeWorldTel-Out2-5' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'Hangup' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '(null)' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '2006-11-06 10:14:10' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '2006-11-06 10:14:10' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '2006-11-06 10:14:32' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '22' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '22' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'ANSWERED' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is 'DOCUMENTATION' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '(null)' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '1162833249.0' Nov 6 10:14:32 DEBUG[17484] pbx.c: Function result is '(null)' Nov 6 10:14:32 DEBUG[17484] channel.c: Hanging up channel 'SIP/polycom-0817d070' Nov 6 10:14:32 DEBUG[17484] chan_sip.c: Hangup call SIP/polycom-0817d070, SIP callid 39e880f0-7c3a473a-5d9b44b@10.130.1.100) Nov 6 10:14:32 DEBUG[17484] chan_sip.c: update_call_counter(polycom) - decrement call limit counter Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21090 Nov 6 10:14:32 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 015 ISeqno: 008 Type: IAX Subclass: HANGUP Nov 6 10:14:32 VERBOSE[17480] logger.c: Timestamp: 21069ms SCall: 00005 DCall: 00212 [xx.xx.xx.xx:4569] Nov 6 10:14:32 VERBOSE[17480] logger.c: CAUSE CODE : 16 Nov 6 10:14:32 VERBOSE[17480] logger.c: Nov 6 10:14:32 DEBUG[17490] app_queue.c: Device 'IAX2/FreeWorldTel-Out2' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Nov 6 10:14:32 DEBUG[17491] app_queue.c: Device 'SIP/polycom' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21120 Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: For call=5, set last=21150 Nov 6 10:14:32 DEBUG[17481] chan_sip.c: Stopping retransmission on '39e880f0-7c3a473a-5d9b44b@10.130.1.100' of Request 102: Match Found Nov 6 10:14:32 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 016 Type: IAX Subclass: ACK Nov 6 10:14:32 VERBOSE[17480] logger.c: Timestamp: 21069ms SCall: 00212 DCall: 00005 [xx.xx.xx.xx:4569] Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: Received packet 8, (6, 4) Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 15 Nov 6 10:14:32 DEBUG[17480] chan_iax2.c: Really destroying 5, having been acked on final message Nov 6 10:14:37 DEBUG[17481] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: New max nontrunk callno is 9 Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Creating new call structure 8 Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: New max nontrunk callno is 10 Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Creating new call structure 9 Nov 6 10:14:37 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00008 DCall: 00000 [xx.xx.xx.xx:4569] Nov 6 10:14:37 VERBOSE[17480] logger.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00009 DCall: 00000 [xx.xx.xx.xx:4569] Nov 6 10:14:37 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00041 DCall: 00008 [xx.xx.xx.xx:4569] Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Received packet 0, (6, 3) Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 0 Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: IAX subclass 3 received Nov 6 10:14:37 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00008 DCall: 00041 [xx.xx.xx.xx:4569] Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Peer lastms 69, historicms 69, maxms 2000 Nov 6 10:14:37 VERBOSE[17480] logger.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00075 DCall: 00009 [xx.xx.xx.xx:4569] Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Received packet 0, (6, 3) Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Cancelling transmission of packet 0 Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: IAX subclass 3 received Nov 6 10:14:37 VERBOSE[17480] logger.c: Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Nov 6 10:14:37 VERBOSE[17480] logger.c: Timestamp: 00006ms SCall: 00009 DCall: 00075 [xx.xx.xx.xx:4569] Nov 6 10:14:37 DEBUG[17480] chan_iax2.c: Peer lastms 70, historicms 70, maxms 2000 Nov 6 10:14:37 DEBUG[17481] chan_sip.c: Stopping retransmission on '70b4ee7c7b297d014f6d82b43702ac6a@10.130.1.65' of Request 102: Match Found Nov 6 10:14:37 DEBUG[17481] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Nov 6 10:14:37 DEBUG[17481] chan_sip.c: Stopping retransmission on '68d4591d72b11c95595221e7150d1018@10.130.1.65' of Request 102: Match Found Nov 6 10:14:42 VERBOSE[17464] logger.c: Waiting for inactivity to perform halt... Nov 6 10:14:42 VERBOSE[17464] logger.c: Executing last minute cleanups Nov 6 10:14:42 VERBOSE[17464] logger.c: == Destroying musiconhold processes Nov 6 10:14:42 DEBUG[17464] res_musiconhold.c: killing 17469! Nov 6 10:14:42 DEBUG[17464] res_musiconhold.c: mpg123 pid 17469 and child died after 4029952 bytes read Nov 6 10:14:42 VERBOSE[17464] logger.c: Asterisk cleanly ending (0). Nov 6 10:14:42 DEBUG[17464] asterisk.c: Asterisk ending (0).