Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/dnsmgr.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk Dynamic Loader loading preload modules: Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/modules.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/manager.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/manager_custom.conf Dec 21 22:59:08 WARNING[12356] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk Management interface listening on port 5038 Dec 21 22:59:08 NOTICE[12356] cdr.c: CDR simple logging enabled. Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/rtp.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk PBX Core Initializing Dec 21 22:59:08 VERBOSE[12356] logger.c: Registering builtin applications: Dec 21 22:59:08 VERBOSE[12356] logger.c: [AbsoluteTimeout] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Answer] Dec 21 22:59:08 VERBOSE[12356] logger.c: [BackGround] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Busy] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Congestion] Dec 21 22:59:08 VERBOSE[12356] logger.c: [DigitTimeout] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Goto] Dec 21 22:59:08 VERBOSE[12356] logger.c: [GotoIf] Dec 21 22:59:08 VERBOSE[12356] logger.c: [GotoIfTime] Dec 21 22:59:08 VERBOSE[12356] logger.c: [ExecIfTime] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Hangup] Dec 21 22:59:08 VERBOSE[12356] logger.c: [NoOp] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Progress] Dec 21 22:59:08 VERBOSE[12356] logger.c: [ResetCDR] Dec 21 22:59:08 VERBOSE[12356] logger.c: [ResponseTimeout] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Ringing] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SayNumber] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SayDigits] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SayAlpha] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SayPhonetic] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SetAccount] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SetAMAFlags] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SetGlobalVar] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SetLanguage] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Set] Dec 21 22:59:08 VERBOSE[12356] logger.c: [SetVar] Dec 21 22:59:08 VERBOSE[12356] logger.c: [ImportVar] Dec 21 22:59:08 VERBOSE[12356] logger.c: [Wait] Dec 21 22:59:08 VERBOSE[12356] logger.c: [WaitExten] Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/enum.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk Dynamic Loader Starting: Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/modules.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_wav.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_wav.so] => (Microsoft WAV format (8000hz Signed Linear)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_pcm.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_pcm.so] => (Raw uLaw 8khz Audio support (PCM)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_au.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_au.so] => (Sun Microsystems AU format (signed linear)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_musiconhold.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_musiconhold.so] => (Music On Hold Resource) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/musiconhold.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/musiconhold_additional.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_oss.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_oss.so] => (OSS Console Channel Driver) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/oss.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Console' (OSS Console Channel Driver) Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_monitor.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_monitor.so] => (Call Monitoring Resource) Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_crypto.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_crypto.so] => (Cryptographic Digital Signatures) Dec 21 22:59:08 DEBUG[12356] res_crypto.c: Key 'freeworlddialup' loaded OK Dec 21 22:59:08 DEBUG[12356] res_crypto.c: Key 'iaxtel' loaded OK Dec 21 22:59:08 DEBUG[12356] res_crypto.c: Key 'voicepulse01' loaded OK Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_adsi.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_adsi.so] => (ADSI Resource) Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_features.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_features.so] => (Call Features Resource) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/features.conf Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'parkedcalls' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '70' priority 1 to parkedcalls Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_indications.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_indications.so] => (Indications Configuration) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/indications.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_agi.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [res_agi.so] => (Asterisk Gateway Interface (AGI)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_dundi.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_dundi.so] => (Distributed Universal Number Discovery (DUNDi)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/dundi.conf Dec 21 22:59:08 DEBUG[12356] pbx_dundi.c: Seeding global EID '00:02:55:7c:98:af' from 'eth0' Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_functions.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_functions.so] => (Builtin dialplan functions) Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_config.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_config.so] => (Text Extension Configuration) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/extensions.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/extensions_additional.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/extensions_custom.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/extensions_trixbox.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/extensions_hud.conf Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-dnd-off' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 1 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 2 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 3 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 4 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 5 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*79' priority 6 to app-dnd-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-dnd-on' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 1 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 2 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 3 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 4 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 5 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*78' priority 6 to app-dnd-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-callwaiting-cwoff' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 1 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 2 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 3 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 4 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 5 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*71' priority 6 to app-callwaiting-cwoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-callwaiting-cwon' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 1 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 2 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 3 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 4 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 5 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*70' priority 6 to app-callwaiting-cwon Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-dialvm' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*98' priority 1 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*98' priority 2 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*98' priority 3 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*98' priority 4 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*98.' priority 1 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*98.' priority 2 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*98.' priority 3 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*98.' priority 4 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*98.' priority 5 to app-dialvm Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-vmmain' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 1 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 2 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 3 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 4 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 5 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*97' priority 6 to app-vmmain Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-recordings' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*77' priority 1 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*77' priority 2 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*77' priority 3 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*99' priority 1 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*99' priority 2 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*99' priority 3 to app-recordings Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-busy-off' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 1 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 2 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 3 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 4 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 5 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*91' priority 6 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 1 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 2 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 3 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 4 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 5 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 6 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 7 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*91.' priority 8 to app-cf-busy-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-busy-off-any' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 1 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 2 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 3 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 4 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 5 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 6 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 7 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 8 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 9 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*92' priority 10 to app-cf-busy-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-busy-on' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 1 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 2 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 3 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 4 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 5 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 6 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 7 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 8 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 9 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 10 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 11 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 12 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 13 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 14 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 15 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 16 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*90' priority 17 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 1 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 2 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 3 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 4 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 5 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 6 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 7 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 8 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*90.' priority 9 to app-cf-busy-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-off' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 1 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 2 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 3 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 4 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 5 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*73' priority 6 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 1 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 2 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 3 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 4 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 5 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 6 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 7 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*73.' priority 8 to app-cf-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-off-any' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 1 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 2 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 3 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 4 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 5 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 6 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 7 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 8 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 9 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*74' priority 10 to app-cf-off-any Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-on' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 1 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 2 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 3 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 4 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 5 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 6 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 7 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 8 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 9 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 10 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 11 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 12 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 13 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 14 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 15 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 16 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*72' priority 17 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 1 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 2 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 3 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 4 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 5 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 6 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 7 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 8 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*72.' priority 9 to app-cf-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-unavailable-off' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 1 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 2 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 3 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 4 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 5 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*53' priority 6 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 1 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 2 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 3 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 4 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 5 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 6 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 7 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*53.' priority 8 to app-cf-unavailable-off Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-cf-unavailable-on' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 1 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 2 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 3 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 4 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 5 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 6 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 7 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 8 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 9 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 10 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 11 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 12 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 13 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 14 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 15 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 16 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*52' priority 17 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 1 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 2 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 3 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 4 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 5 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 6 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 7 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 8 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*52.' priority 9 to app-cf-unavailable-on Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-userlogonoff' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*12' priority 1 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*12' priority 2 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*11' priority 1 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*11' priority 2 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*11.' priority 1 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*11.' priority 2 to app-userlogonoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'app-zapbarge' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 1 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 2 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 3 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 4 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 5 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '888' priority 6 to app-zapbarge Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'ext-test' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '7777' priority 1 to ext-test Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '666' priority 1 to ext-test Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to ext-test Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'ext-did' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_[*#X].' priority 1 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_[*#X].' priority 2 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_[*#X].' priority 3 to ext-did Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'ext-local' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '101' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '101' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '101' priority -1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*101' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*101' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '102' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '102' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '102' priority -1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*102' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*102' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '201' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '201' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '201' priority -1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*201' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*201' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '202' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '202' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '202' priority -1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*202' priority 1 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*202' priority 2 to ext-local Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'outbound-allroutes' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'foo' priority 1 to outbound-allroutes Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'outrt-001-9_outside' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_9.' priority 1 to outrt-001-9_outside Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_9.' priority 2 to outrt-001-9_outside Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-internal-additional' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to from-internal-additional Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-internal-trixbox' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_**.' priority 1 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*61' priority 1 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*61' priority 2 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*61' priority 3 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*62' priority 1 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*62' priority 2 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*62' priority 3 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 1 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 2 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 3 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 4 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 5 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 6 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 7 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 8 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 9 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 10 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '611' priority 11 to from-internal-trixbox Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-meetme' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to custom-meetme Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to custom-meetme Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-wakeupext' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to custom-wakeupext Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to custom-wakeupext Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to custom-wakeupext Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-speed-dial' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 1 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 2 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 3 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 4 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 5 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3003XX.' priority 6 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 1 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 2 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 3 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 102 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 103 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 104 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 105 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 106 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 107 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_3XX' priority 108 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 1 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 2 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 3 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 4 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 102 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 103 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 104 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 105 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*3XX' priority 106 to custom-speed-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'menu-SysSpeed' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '1' priority 1 to menu-SysSpeed Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'xfer-to-hold' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_X.' priority 1 to xfer-to-hold Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'xfer-to-vm' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXXX' priority 1 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXXX' priority 2 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXXX' priority 3 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXXX' priority 4 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXX' priority 1 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXX' priority 2 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXX' priority 3 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XXX' priority 4 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XX' priority 1 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XX' priority 2 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XX' priority 3 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_*86[1-8]XX' priority 4 to xfer-to-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'barge' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_1X.' priority 1 to barge Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_2X.' priority 1 to barge Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-100users' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '71' priority 1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '71' priority -1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '72' priority 1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '72' priority -1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '73' priority 1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '73' priority -1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '74' priority 1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '74' priority -1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '75' priority 1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '75' priority -1 to custom-100users Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-200users' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '86' priority 1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '86' priority -1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '87' priority 1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '87' priority -1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '88' priority 1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '88' priority -1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '89' priority 1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '89' priority -1 to custom-200users Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-internal-custom' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '71' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '71' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '72' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '72' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '73' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '73' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '74' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '74' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '75' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '75' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '76' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '76' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '77' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '77' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '78' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '78' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '79' priority 1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '79' priority -1 to from-internal-custom Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-setpagingheader' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-setpagingheader Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'custom-jeffpark' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to custom-jeffpark Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to custom-jeffpark Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to custom-jeffpark Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to custom-jeffpark Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_7X' priority 1 to custom-jeffpark Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-trunk' Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-pstn' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'fax' priority 1 to from-pstn Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-did-direct' Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dial' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 20 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 21 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 22 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 23 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 30 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 31 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 32 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 33 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 34 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 35 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 36 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 37 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 38 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 39 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 40 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 41 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 42 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 43 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 44 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 45 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 50 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 51 to macro-dial Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-exten-vm' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'dolocaldial' priority 1 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'dolocaldial' priority 2 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docfu' priority 1 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docfu' priority 2 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docfu' priority 3 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 1 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 2 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 3 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 4 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 1 to macro-exten-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-vm' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 3 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 4 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-DIRECTDIAL' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-DIRECTDIAL' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-DIRECTDIAL' priority 3 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-DIRECTDIAL' priority 4 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 3 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'o' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'o' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'a' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'a' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'a' priority 3 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-FAILED' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-FAILED' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-SUCCESS' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-SUCCESS' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-USEREXIT' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'exit-USEREXIT' priority 2 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 't' priority 1 to macro-vm Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-get-vmcontext' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-get-vmcontext Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-get-vmcontext Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 200 to macro-get-vmcontext Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 300 to macro-get-vmcontext Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-fixcid' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-fixcid Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-rg-group' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-rg-group Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-outisbusy' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-outisbusy Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-outisbusy Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-outisbusy Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-hangupcall' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-hangupcall Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-hangupcall Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-hangupcall Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-hangupcall Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-faxreceive' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-faxreceive Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-faxreceive Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-faxreceive Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 103 to macro-faxreceive Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 104 to macro-faxreceive Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dialout' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 109 to macro-dialout Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dialout-default' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 105 to macro-dialout-default Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dialout-trunk' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 15 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 16 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 17 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 18 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 19 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 20 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 21 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 22 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 23 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 24 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 25 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 1 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 2 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 3 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 4 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 1 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to macro-dialout-trunk Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-agent-add' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-agent-add Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-agent-del' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-agent-del Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dialout-enum' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 15 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 16 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 17 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 18 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 19 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 20 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 21 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 22 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 23 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 24 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 25 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 26 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 27 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 28 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 29 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 30 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 31 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 32 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 33 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 34 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 35 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 36 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 37 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 38 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 39 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 40 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 41 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 42 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 43 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 44 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 45 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 46 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 47 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 48 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 49 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 50 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 51 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 52 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 1 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 2 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 3 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-BUSY' priority 4 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_s-.' priority 1 to macro-dialout-enum Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-record-enable' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 999 to macro-record-enable Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-dumpvars' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 15 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 16 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 17 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 18 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 19 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 20 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 21 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 22 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 23 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 24 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 25 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 26 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 27 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 28 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 29 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 30 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 31 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 32 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 33 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 34 to macro-dumpvars Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-user-logon' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 15 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 16 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 17 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 18 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 19 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 20 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 21 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 1 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 2 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 3 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 4 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 5 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 6 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-ALREADYLOGGEDON' priority 1 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-ALREADYLOGGEDON' priority 2 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-ALREADYLOGGEDON' priority 3 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-NOPASSWORD' priority 1 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-NOPASSWORD' priority 2 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-NOPASSWORD' priority 3 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-NOPASSWORD' priority 4 to macro-user-logon Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-user-logoff' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 1 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 2 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 3 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's-FIXED' priority 4 to macro-user-logoff Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-systemrecording' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'dorecord' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'dorecord' priority 2 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'dorecord' priority 3 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docheck' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docheck' priority 2 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'docheck' priority 3 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'confmenu' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'confmenu' priority 2 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'confmenu' priority 3 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'confmenu' priority 4 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'confmenu' priority 5 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '1' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '*' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 't' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 't' priority 2 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'i' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'i' priority 2 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to macro-systemrecording Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-user-callerid' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-user-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-outbound-callerid' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 13 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 14 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 15 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 16 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 17 to macro-outbound-callerid Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'macro-privacy-mgr' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 109 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 110 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 111 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 112 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 113 to macro-privacy-mgr Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-sip-external' Dec 21 22:59:08 WARNING[12356] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 647 Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_.' priority 1 to from-sip-external Dec 21 22:59:08 WARNING[12356] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 648 Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_.' priority 2 to from-sip-external Dec 21 22:59:08 WARNING[12356] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 649 Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_.' priority 3 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'i' priority 1 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 't' priority 1 to from-sip-external Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-internal' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to from-internal Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to from-internal Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'from-zaptel' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_X.' priority 1 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension '_X.' priority 2 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 3 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 4 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 5 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 6 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 7 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 8 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 9 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 10 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 11 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 12 to from-zaptel Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'ext-fax' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'in_fax' priority 1 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'in_fax' priority 2 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'in_fax' priority 3 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'in_fax' priority 4 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'analog_fax' priority 1 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'analog_fax' priority 2 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'analog_fax' priority 3 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'analog_fax' priority 4 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'out_fax' priority 1 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'out_fax' priority 2 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 1 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 'h' priority 2 to ext-fax Dec 21 22:59:08 DEBUG[12356] pbx.c: Registered context 'default' Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 1 to default Dec 21 22:59:08 DEBUG[12356] pbx.c: Added extension 's' priority 2 to default Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-did-direct' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'from-pstn-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'ext-did-direct' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'custom-meetme' tries includes nonexistent context 'ext-meetme' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'from-internal-additional-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'ext-group' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'outrt-001-9_outside' tries includes nonexistent context 'outrt-001-9_outside-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'outbound-allroutes' tries includes nonexistent context 'outbound-allroutes-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-local' tries includes nonexistent context 'ext-local-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-did' tries includes nonexistent context 'ext-did-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-test' tries includes nonexistent context 'ext-test-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-zapbarge' tries includes nonexistent context 'app-zapbarge-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-userlogonoff' tries includes nonexistent context 'app-userlogonoff-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-unavailable-on' tries includes nonexistent context 'app-cf-unavailable-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-unavailable-off' tries includes nonexistent context 'app-cf-unavailable-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-on' tries includes nonexistent context 'app-cf-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-off-any' tries includes nonexistent context 'app-cf-off-any-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-off' tries includes nonexistent context 'app-cf-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-on' tries includes nonexistent context 'app-cf-busy-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-off-any' tries includes nonexistent context 'app-cf-busy-off-any-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-off' tries includes nonexistent context 'app-cf-busy-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-recordings' tries includes nonexistent context 'app-recordings-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-vmmain' tries includes nonexistent context 'app-vmmain-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dialvm' tries includes nonexistent context 'app-dialvm-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-callwaiting-cwon' tries includes nonexistent context 'app-callwaiting-cwon-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-callwaiting-cwoff' tries includes nonexistent context 'app-callwaiting-cwoff-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dnd-on' tries includes nonexistent context 'app-dnd-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dnd-off' tries includes nonexistent context 'app-dnd-off-custom' Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_loopback.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_loopback.so] => (Loopback Switch) Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_ael.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_ael.so] => (Asterisk Extension Language Compiler) Dec 21 22:59:08 WARNING[12356] pbx_ael.c: Unable to open '/etc/asterisk/extensions.ael': No such file or directory Dec 21 22:59:08 WARNING[12356] pbx.c: Requested contexts didn't get merged Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-did-direct' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'from-pstn-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'ext-did-direct' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-pstn' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'custom-meetme' tries includes nonexistent context 'ext-meetme' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'from-internal-additional-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'ext-group' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'from-internal-additional' tries includes nonexistent context 'ext-findmefollow' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'outrt-001-9_outside' tries includes nonexistent context 'outrt-001-9_outside-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'outbound-allroutes' tries includes nonexistent context 'outbound-allroutes-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-local' tries includes nonexistent context 'ext-local-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-did' tries includes nonexistent context 'ext-did-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'ext-test' tries includes nonexistent context 'ext-test-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-zapbarge' tries includes nonexistent context 'app-zapbarge-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-userlogonoff' tries includes nonexistent context 'app-userlogonoff-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-unavailable-on' tries includes nonexistent context 'app-cf-unavailable-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-unavailable-off' tries includes nonexistent context 'app-cf-unavailable-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-on' tries includes nonexistent context 'app-cf-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-off-any' tries includes nonexistent context 'app-cf-off-any-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-off' tries includes nonexistent context 'app-cf-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-on' tries includes nonexistent context 'app-cf-busy-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-off-any' tries includes nonexistent context 'app-cf-busy-off-any-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-cf-busy-off' tries includes nonexistent context 'app-cf-busy-off-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-recordings' tries includes nonexistent context 'app-recordings-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-vmmain' tries includes nonexistent context 'app-vmmain-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dialvm' tries includes nonexistent context 'app-dialvm-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-callwaiting-cwon' tries includes nonexistent context 'app-callwaiting-cwon-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-callwaiting-cwoff' tries includes nonexistent context 'app-callwaiting-cwoff-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dnd-on' tries includes nonexistent context 'app-dnd-on-custom' Dec 21 22:59:08 WARNING[12356] pbx.c: Context 'app-dnd-off' tries includes nonexistent context 'app-dnd-off-custom' Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_realtime.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_realtime.so] => (Realtime Switch) Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_spool.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [pbx_spool.so] => (Outgoing Spool Support) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_zap.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_zap.so] => (Zapata Telephony w/PRI) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/zapata.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/zapata-auto.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/zapata_additional.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Zap' (Zapata Telephony Driver w/PRI) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_local.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_local.so] => (Local Proxy Channel) Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Local' (Local Proxy Channel Driver) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_sip.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_sip.so] => (Session Initiation Protocol (SIP)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/sip.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/sip_nat.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/sip_additional.conf Dec 21 22:59:08 DEBUG[12356] db.c: Unable to find key '201' in family 'SIP/Registry' Dec 21 22:59:08 DEBUG[12356] db.c: Unable to find key '202' in family 'SIP/Registry' Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/sip_notify.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_skinny.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_skinny.so] => (Skinny Client Control Protocol (Skinny)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/skinny.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_mgcp.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_mgcp.so] => (Media Gateway Control Protocol (MGCP)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/mgcp.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_agent.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_agent.so] => (Agent Proxy Channel) Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Agent' (Call Agent Proxy Channel) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/agents.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_features.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_features.so] => (Feature Proxy Channel) Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Feature' (Feature Proxy Channel Driver) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_phone.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_phone.so] => (Linux Telephony API Support) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/phone.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'Phone' (Standard Linux Telephony API Driver) Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_iax2.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [chan_iax2.so] => (Inter Asterisk eXchange (Ver 2)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/iax.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/iax_additional.conf Dec 21 22:59:08 DEBUG[12356] channel.c: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/iaxprov.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_readfile.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_readfile.so] => (Stores output of file into a variable) Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_custom.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_custom.so] => (Customizable Comma Separated Values CDR Backend) Dec 21 22:59:08 WARNING[12356] cdr_custom.c: Failed to load configuration file. Module not activated. Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_transfer.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_transfer.so] => (Transfer) Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_csv.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_csv.so] => (Comma Separated Values CDR Backend) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_directory.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_directory.so] => (Extension Directory) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_lookupcidname.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_lookupcidname.so] => (Look up CallerID Name from local database) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_record.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_record.so] => (Trivial Record Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcidname.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcidname.so] => (Set CallerID Name) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_alaw.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_alaw.so] => (A-law Coder/Decoder) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_realtime.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_realtime.so] => (Realtime Data Lookup/Rewrite) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_curl.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_curl.so] => (Load external URL) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_gsm.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_gsm.so] => (GSM/PCM16 (signed linear) Codec Translator) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_h263.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_h263.so] => (Raw h263 data) Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_manager.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [cdr_manager.so] => (Asterisk Call Manager CDR Backend) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_stack.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_stack.so] => (Stack Routines) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_mp3.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_mp3.so] => (Silly MP3 Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcidnum.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcidnum.so] => (Set CallerID Number) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_adsiprog.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_adsiprog.so] => (Asterisk ADSI Programming Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_cdr.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_cdr.so] => (Tell Asterisk to not maintain a CDR for the current call) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_eval.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_eval.so] => (Reevaluates strings) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_system.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_system.so] => (Generic System() application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_festival.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_festival.so] => (Simple Festival Interface) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_milliwatt.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_milliwatt.so] => (Digital Milliwatt (mu-law) Test Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_flash.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_flash.so] => (Flash zap trunk application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_read.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_read.so] => (Read Variable Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_adpcm.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_adpcm.so] => (Adaptive Differential PCM Coder/Decoder) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_ilbc.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_ilbc.so] => (Raw iLBC data) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_alarmreceiver.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_alarmreceiver.so] => (Alarm Receiver for Asterisk) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/alarmreceiver.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapscan.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapscan.so] => (Scan Zap channels application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_uri.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_uri.so] => (URI encode/decode functions) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_ices.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_ices.so] => (Encode and Stream via icecast and ices) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_jpeg.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_jpeg.so] => (JPEG (Joint Picture Experts Group) Image Format) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapras.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapras.so] => (Zap RAS Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_cut.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_cut.so] => (Cut out information from a string) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setrdnis.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setrdnis.so] => (Set RDNIS Number) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_gsm.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_gsm.so] => (Raw GSM data) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_externalivr.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_externalivr.so] => (External IVR Interface Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_ulaw.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_ulaw.so] => (Mu-law Coder/Decoder) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_enum.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_enum.so] => (ENUM Related Functions) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_privacy.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_privacy.so] => (Require phone number to be entered, if no CallerID sent) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_math.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_math.so] => (Basic Math Functions) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_authenticate.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_authenticate.so] => (Authentication Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_db.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_db.so] => (Database Access Functions) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_talkdetect.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_talkdetect.so] => (Playback with Talk Detection) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcdruserfield.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcdruserfield.so] => (CDR user field apps) Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_callerid.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [func_callerid.so] => (Caller ID related dialplan function) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g726.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g726.so] => (Raw G.726 (16/24/32/40kbps) data) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_groupcount.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_groupcount.so] => (Group Management Routines) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_directed_pickup.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_directed_pickup.so] => (Directed Call Pickup Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_txtcidname.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_txtcidname.so] => (TXTCIDName) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapbarge.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapbarge.so] => (Barge in on Zap channel application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_echo.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_echo.so] => (Simple Echo Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_getcpeid.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_getcpeid.so] => (Get ADSI CPE ID) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_exec.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_exec.so] => (Executes applications) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_md5.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_md5.so] => (MD5 checksum applications) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_page.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_page.so] => (Page Multiple Phones) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_url.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_url.so] => (Send URL Applications) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_parkandannounce.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_parkandannounce.so] => (Call Parking and Announce Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_verbose.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_verbose.so] => (Send verbose output) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sms.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sms.so] => (SMS/PSTN handler) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_macro.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_macro.so] => (Extension Macros) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_controlplayback.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_controlplayback.so] => (Control Playback Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_senddtmf.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_senddtmf.so] => (Send DTMF digits Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_nbscat.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_nbscat.so] => (Silly NBS Stream Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dumpchan.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dumpchan.so] => (Dump Info About The Calling Channel) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_image.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_image.so] => (Image Transmission Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_settransfercapability.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_settransfercapability.so] => (Set ISDN Transfer Capability) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_disa.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_disa.so] => (DISA (Direct Inward System Access) Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_chanspy.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_chanspy.so] => (Listen to the audio of an active channel ) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_random.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_random.so] => (Random goto) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_a_mu.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_a_mu.so] => (A-law and Mulaw direct Coder/Decoder) Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_pcm_alaw.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_pcm_alaw.so] => (Raw aLaw 8khz PCM Audio support) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_userevent.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_userevent.so] => (Custom User Event Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_while.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_while.so] => (While Loops and Conditional Execution) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_test.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_test.so] => (Interface Test Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_mixmonitor.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_mixmonitor.so] => (Mixed Audio Monitoring Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sendtext.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sendtext.so] => (Send Text Applications) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_playback.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_playback.so] => (Sound File Playback Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_waitforring.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_waitforring.so] => (Waits until first ring after time) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_lookupblacklist.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_lookupblacklist.so] => (Look up Caller*ID name/number from blacklist database) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_meetme.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_meetme.so] => (MeetMe conference bridge) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/meetme.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_softhangup.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_softhangup.so] => (Hangs up the requested channel) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_queue.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_queue.so] => (True Call Queueing) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/queues.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/queues_additional.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_lpc10.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_lpc10.so] => (LPC10 2.4kbps (signed linear) Voice Coder) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_chanisavail.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_chanisavail.so] => (Check channel availability) Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_g726.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_g726.so] => (ITU G.726-32kbps G726 Transcoder) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/codecs.conf Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from g723 to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from gsm to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to ulaw, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 9 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_enumlookup.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_enumlookup.so] => (ENUM Lookup) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/enum.conf Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_ilbc.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [codec_ilbc.so] => (iLBC/PCM16 (signed linear) Codec Translator) Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from g723 to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from gsm to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to ulaw, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 9 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 9 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Resetting translation matrix Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from g723 to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 32 cost path from g723 to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from gsm to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 31 cost path from gsm to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to g723, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to ulaw, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 7 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 32 cost path from unknown to unknown, via 2 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 31 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 4 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 8 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 33 cost path from ulaw to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 5 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 31 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 9 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 35 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to g723, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to gsm, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 9 cost path from unknown to ulaw, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 6 cost path from unknown to unknown, via 6 Dec 21 22:59:08 DEBUG[12356] translate.c: Discovered 10 cost path from unknown to unknown, via 6 Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_hasnewvoicemail.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_hasnewvoicemail.so] => (Indicator for whether a voice mailbox has messages in a given folder.) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_voicemail.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_voicemail.so] => (Comedian Mail (Voicemail System)) Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/voicemail.conf Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/vm_general.inc Dec 21 22:59:08 DEBUG[12356] config.c: Parsing /etc/asterisk/vm_email.inc Dec 21 22:59:08 DEBUG[12356] app_voicemail.c: VM CID Info before msg disabled globally Dec 21 22:59:08 DEBUG[12356] app_voicemail.c: Send Voicemail msg disabled globally Dec 21 22:59:08 DEBUG[12356] app_voicemail.c: ENVELOPE before msg enabled globally Dec 21 22:59:08 DEBUG[12356] app_voicemail.c: Duration info before msg enabled globally Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dictate.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dictate.so] => (Virtual Dictation Machine) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dial.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_dial.so] => (Dialing Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_vox.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_vox.so] => (Dialogic VOX (ADPCM) File Format) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g723.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g723.so] => (G.723.1 Simple Timestamp File Format) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g729.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_g729.so] => (Raw G729 data) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_forkcdr.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_forkcdr.so] => (Fork The CDR into 2 separate entities.) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapateller.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_zapateller.so] => (Block Telemarketers with Special Information Tone) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_wav_gsm.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_wav_gsm.so] => (Microsoft WAV format (Proprietary GSM)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_waitforsilence.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_waitforsilence.so] => (Wait For Silence) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sayunixtime.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_sayunixtime.so] => (Say time) Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_sln.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [format_sln.so] => (Raw Signed Linear Audio support (SLN)) Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcallerid.so]Dec 21 22:59:08 VERBOSE[12356] logger.c: [app_setcallerid.so] => (Set CallerID Application) Dec 21 22:59:08 VERBOSE[12356] logger.c: Asterisk Ready. Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Stopping retransmission on '38868d4d0b4832505c849a1c2632131d@192.168.1.90' of Request 102: Match Found Dec 21 22:59:09 DEBUG[12369] chan_sip.c: Stopping retransmission on '3d071eb96b0156724c4a4fa85bf1517e@192.168.1.90' of Request 102: Match Found Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Challenge' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Login' Dec 21 22:59:16 DEBUG[12376] config.c: Parsing /etc/asterisk/manager.conf Dec 21 22:59:16 DEBUG[12376] config.c: Parsing /etc/asterisk/manager_custom.conf Dec 21 22:59:16 WARNING[12376] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Dec 21 22:59:16 DEBUG[12376] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Dec 21 22:59:16 DEBUG[12376] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Dec 21 22:59:16 DEBUG[12376] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Dec 21 22:59:16 DEBUG[12376] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/101' in family 'cfb' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/202' in family 'cfb' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/201' in family 'cfb' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/102' in family 'cfb' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/101' in family 'dnd' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/202' in family 'dnd' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/201' in family 'dnd' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] db.c: Unable to find key 'SIP/102' in family 'dnd' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'QueueStatus' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Status' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'ZapShowChannels' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'MailboxStatus' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'MailboxStatus' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'MailboxStatus' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'MailboxStatus' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:16 DEBUG[12376] manager.c: Manager received command 'MailboxCount' Dec 21 22:59:18 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 22:59:18 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 23:01:18 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 23:01:18 DEBUG[12376] manager.c: Manager received command 'Command' Dec 21 23:02:26 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: Dec 21 23:02:26 DEBUG[12369] chan_sip.c: Header 0: (0) Dec 21 23:02:26 VERBOSE[12369] logger.c: --- (0 headers 0 lines) Nat keepalive --- Dec 21 23:02:28 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.34:2069: INVITE sip:101@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-e3tv2igt81yb;rport From: ;tag=j811bts70y To: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 1 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: keys="3" User-Agent: snom300/6.5.1 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 471 v=0 o=root 44659436 44659436 IN IP4 192.168.1.34 s=call c=IN IP4 192.168.1.34 t=0 0 m=audio 52066 RTP/AVP 0 8 9 2 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:aflYXCWGVVMdYixDzZPV+ma672fNyJgpVy019pEX a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=encryption:optional a=sendrecv Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 0: INVITE sip:101@192.168.1.90;user=phone SIP/2.0 (46) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-e3tv2igt81yb;rport (68) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=j811bts70y (43) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 3: To: (37) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE (55) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 INVITE (14) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 8: P-Key-Flags: keys="3" (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 9: User-Agent: snom300/6.5.1 (25) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 10: Accept: application/sdp (23) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 12: Allow-Events: talk, hold, refer (31) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 15: Min-SE: 90 (10) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 16: Content-Type: application/sdp (29) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 17: Content-Length: 471 (19) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 18: (0) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: o=root 44659436 44659436 IN IP4 192.168.1.34 (44) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: c=IN IP4 192.168.1.34 (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: m=audio 52066 RTP/AVP 0 8 9 2 3 18 4 101 (40) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:aflYXCWGVVMdYixDzZPV+ma672fNyJgpVy019pEX (82) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=ptime:20 (10) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=encryption:optional (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=sendrecv (10) Dec 21 23:02:28 VERBOSE[12369] logger.c: --- (18 headers 19 lines) --- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE - INVITE (With RTP) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Begin: parsing SIP "Supported: timer, 100rel, replaces, callerid" Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Found SIP option: -timer- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Matched SIP option: timer Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Found SIP option: -100rel- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Matched SIP option: 100rel Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Found SIP option: -replaces- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Matched SIP option: replaces Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Found SIP option: -callerid- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Found no match for SIP option: callerid (Please file bug report!) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: * SIP extension value: 7 for call 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:28 VERBOSE[12369] logger.c: Using INVITE request as basis request - 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:28 VERBOSE[12369] logger.c: Sending to 192.168.1.34 : 2069 (NAT) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Setting NAT on RTP to 0 Dec 21 23:02:28 VERBOSE[12369] logger.c: Reliably Transmitting (no NAT) to 192.168.1.34:2069: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-e3tv2igt81yb;rport;received=192.168.1.34 From: ;tag=j811bts70y To: ;tag=as71a19bdf Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="242d9689" Content-Length: 0 --- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #9 Dec 21 23:02:28 VERBOSE[12369] logger.c: Scheduling destruction of call '3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE' in 15000 ms Dec 21 23:02:28 VERBOSE[12369] logger.c: Found user '102' Dec 21 23:02:28 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.34:2069: ACK sip:101@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-e3tv2igt81yb;rport From: ;tag=j811bts70y To: ;tag=as71a19bdf Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 1 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 0: ACK sip:101@192.168.1.90;user=phone SIP/2.0 (43) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-e3tv2igt81yb;rport (68) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=j811bts70y (43) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=as71a19bdf (52) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE (55) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 ACK (11) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 9: (0) Dec 21 23:02:28 VERBOSE[12369] logger.c: --- (9 headers 0 lines) --- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Their Tag j811bts70y Our tag: as71a19bdf Dec 21 23:02:28 DEBUG[12369] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 21 23:02:28 DEBUG[12369] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #9 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Stopping retransmission on '3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE' of Response 1: Match Found Dec 21 23:02:28 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.34:2069: INVITE sip:101@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-g6r15o67re4s;rport From: ;tag=j811bts70y To: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 2 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: keys="3" User-Agent: snom300/6.5.1 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Proxy-Authorization: Digest username="102",realm="asterisk",nonce="242d9689",uri="sip:101@192.168.1.90;user=phone",response="3874158f6cc7b209e081d210b03b90ad",algorithm=md5 Content-Type: application/sdp Content-Length: 471 v=0 o=root 44659436 44659436 IN IP4 192.168.1.34 s=call c=IN IP4 192.168.1.34 t=0 0 m=audio 52066 RTP/AVP 0 8 9 2 3 18 4 101 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:aflYXCWGVVMdYixDzZPV+ma672fNyJgpVy019pEX a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=encryption:optional a=sendrecv Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 0: INVITE sip:101@192.168.1.90;user=phone SIP/2.0 (46) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-g6r15o67re4s;rport (68) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=j811bts70y (43) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 3: To: (37) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE (55) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 5: CSeq: 2 INVITE (14) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 8: P-Key-Flags: keys="3" (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 9: User-Agent: snom300/6.5.1 (25) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 10: Accept: application/sdp (23) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 12: Allow-Events: talk, hold, refer (31) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 15: Min-SE: 90 (10) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 16: Proxy-Authorization: Digest username="102",realm="asterisk",nonce="242d9689",uri="sip:101@192.168.1.90;user=phone",response="3874158f6cc7b209e081d210b03b90ad",algorithm=md5 (172) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 17: Content-Type: application/sdp (29) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 18: Content-Length: 471 (19) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 19: (0) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: o=root 44659436 44659436 IN IP4 192.168.1.34 (44) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: c=IN IP4 192.168.1.34 (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: m=audio 52066 RTP/AVP 0 8 9 2 3 18 4 101 (40) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:aflYXCWGVVMdYixDzZPV+ma672fNyJgpVy019pEX (82) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=ptime:20 (10) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=encryption:optional (21) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Line: a=sendrecv (10) Dec 21 23:02:28 VERBOSE[12369] logger.c: --- (19 headers 19 lines) --- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Their Tag j811bts70y Our tag: as71a19bdf Dec 21 23:02:28 DEBUG[12369] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 21 23:02:28 VERBOSE[12369] logger.c: Using INVITE request as basis request - 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:28 VERBOSE[12369] logger.c: Sending to 192.168.1.34 : 2069 (NAT) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Setting NAT on RTP to 0 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found user '102' Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 0 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 8 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 9 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 2 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 3 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 18 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 4 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found RTP audio format 101 Dec 21 23:02:28 VERBOSE[12369] logger.c: Peer audio RTP is at port 192.168.1.34:52066 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Peer audio RTP is at port 192.168.1.34:52066 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format pcmu Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format pcma Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format g722 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format g726-32 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format gsm Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format g729 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format g723 Dec 21 23:02:28 VERBOSE[12369] logger.c: Found description format telephone-event Dec 21 23:02:28 VERBOSE[12369] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x11f (g723|gsm|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0xc (ulaw|alaw) Dec 21 23:02:28 VERBOSE[12369] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Checking SIP call limits for device 102 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Updating call counter for incoming call Dec 21 23:02:28 VERBOSE[12369] logger.c: Looking for 101 in custom-100users (domain 192.168.1.90;user=phone) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: build_route: Contact hop: ;flow-id=1 Dec 21 23:02:28 VERBOSE[12369] logger.c: list_route: hop: Dec 21 23:02:28 VERBOSE[12369] logger.c: Transmitting (no NAT) to 192.168.1.34:2069: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-g6r15o67re4s;rport;received=192.168.1.34 From: ;tag=j811bts70y To: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 21 23:02:28 DEBUG[12362] chan_sip.c: Checking device state for peer 102 Dec 21 23:02:28 DEBUG[12362] devicestate.c: Changing state for SIP/102 - state 2 (In use) Dec 21 23:02:28 DEBUG[12362] chan_sip.c: Checking device state for peer 102 Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Macro' Dec 21 23:02:28 DEBUG[12663] app_queue.c: Device 'SIP/102' changed to state '2' (In use) but we don't care because they're not a member of any queue. Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Macro("SIP/102-093d29a8", "exten-vm|101|101") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Macro' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Macro("SIP/102-093d29a8", "user-callerid") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'GotoIf' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing GotoIf("SIP/102-093d29a8", "0?report") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Not taking any branch Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'GotoIf' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing GotoIf("SIP/102-093d29a8", "0?start") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Not taking any branch Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '102' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "REALCALLERIDNUM=102") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'NoOp' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing NoOp("SIP/102-093d29a8", "REALCALLERIDNUM is 102") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '102' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "AMPUSER=102") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '102' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "AMPUSERCIDNAME=102") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'GotoIf' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing GotoIf("SIP/102-093d29a8", "0?report") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Not taking any branch Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "CALLERID(all)=102 <102>") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '"102" <102>' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'NoOp' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing NoOp("SIP/102-093d29a8", "Using CallerID "102" <102>") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "FROMCONTEXT=exten-vm") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "VMBOX=101") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "EXTTOCALL=101") in new stack Dec 21 23:02:28 DEBUG[12662] db.c: Unable to find key '101' in family 'CFU' Dec 21 23:02:28 DEBUG[12662] func_db.c: DB: CFU/101 not found in database. Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "CFUEXT=") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '1' Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '1' Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '15' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Set' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Set("SIP/102-093d29a8", "RT=15") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Macro' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Macro("SIP/102-093d29a8", "record-enable|101|IN") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Function result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'GotoIf' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing GotoIf("SIP/102-093d29a8", "0 > 0?2:4") in new stack Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Goto (macro-record-enable,s,4) Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'AGI' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing AGI("SIP/102-093d29a8", "recordingcheck|20061221-230228|1166767348.0") in new stack Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck Dec 21 23:02:28 VERBOSE[12662] logger.c: recordingcheck|20061221-230228|1166767348.0: Inbound recording not enabled Dec 21 23:02:28 VERBOSE[12662] logger.c: -- AGI Script recordingcheck completed, returning 0 Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'NoOp' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing NoOp("SIP/102-093d29a8", "No recording needed") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Expression result is '0' Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'GotoIf' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing GotoIf("SIP/102-093d29a8", "0?dolocaldial|1") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Not taking any branch Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Macro' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Macro("SIP/102-093d29a8", "dial|15|Tt|101") in new stack Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'AGI' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing AGI("SIP/102-093d29a8", "dialparties.agi") in new stack Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi Dec 21 23:02:28 VERBOSE[12662] logger.c: dialparties.agi: Starting New Dialparties.agi Dec 21 23:02:28 VERBOSE[12662] logger.c: -- dialparties.agi: priority is 1 Dec 21 23:02:28 VERBOSE[12662] logger.c: dialparties.agi: Caller ID name is '102' number is '102' Dec 21 23:02:28 VERBOSE[12662] logger.c: dialparties.agi: Methodology of ring is 'none' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- dialparties.agi: Added extension 101 to extension map Dec 21 23:02:28 DEBUG[12662] db.c: Unable to find key '101' in family 'CF' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- dialparties.agi: Extension 101 cf is disabled Dec 21 23:02:28 DEBUG[12662] db.c: Unable to find key '101' in family 'DND' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- dialparties.agi: Extension 101 do not disturb is disabled Dec 21 23:02:28 DEBUG[12662] db.c: Unable to find key '101' in family 'CFB' Dec 21 23:02:28 DEBUG[12662] db.c: Unable to find key '101' in family 'CFU' Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: extnum: 101 Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: exthascw: 1 Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: exthascfb: 0 Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: extcfb: Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: exthascfu: 0 Dec 21 23:02:28 VERBOSE[12662] logger.c: > dialparties.agi: extcfu: Dec 21 23:02:28 VERBOSE[12662] logger.c: -- dialparties.agi: DbSet CALLTRACE/101 to 102 Dec 21 23:02:28 VERBOSE[12662] logger.c: -- AGI Script dialparties.agi completed, returning 0 Dec 21 23:02:28 DEBUG[12662] pbx.c: Launching 'Dial' Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Executing Dial("SIP/102-093d29a8", "SIP/101|15|Tt") in new stack Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Setting NAT on RTP to 0 Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-dial-s-10. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable MACRO_DEPTH. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable ds. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-dial-s-1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable ARG3. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable ARG2. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable ARG1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable MACRO_PRIORITY. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable MACRO_CONTEXT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable MACRO_EXTEN. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-9. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-8. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-record-enable-s-5. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-record-enable-s-4. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-record-enable-s-1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-7. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable RT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-6. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable CFUEXT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-5. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable EXTTOCALL. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-4. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable VMBOX. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-3. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable FROMCONTEXT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-2. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-8. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable AMPUSERCIDNAME. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable DB_RESULT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable AMPUSER. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable REALCALLERIDNUM. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-macro-exten-vm-s-1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable STACK-custom-100users-101-1. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable SIPCALLID. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable SIPUSERAGENT. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable SIPDOMAIN. Dec 21 23:02:28 DEBUG[12662] channel.c: Not copying variable SIPURI. Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Outgoing Call for 101 Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Updating call counter for outgoing call Dec 21 23:02:28 VERBOSE[12662] logger.c: We're at 192.168.1.90 port 19388 Dec 21 23:02:28 VERBOSE[12662] logger.c: Adding codec 0x4 (ulaw) to SDP Dec 21 23:02:28 VERBOSE[12662] logger.c: Adding codec 0x8 (alaw) to SDP Dec 21 23:02:28 VERBOSE[12662] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 0: INVITE sip:101@192.168.1.31:2158;line=gv8x1x75 SIP/2.0 (54) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb (57) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 2: From: "102" ;tag=as141708c8 (49) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 3: To: (45) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 4: Contact: (31) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 5: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 6: CSeq: 102 INVITE (16) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 9: Date: Fri, 22 Dec 2006 06:02:28 GMT (35) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 11: Content-Type: application/sdp (29) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 12: Content-Length: 240 (19) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Header 13: (0) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: v=0 (3) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: o=root 12356 12356 IN IP4 192.168.1.90 (38) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: s=session (9) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: c=IN IP4 192.168.1.90 (21) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: m=audio 19388 RTP/AVP 0 8 101 (29) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:28 DEBUG[12662] chan_sip.c: Line: a=silenceSupp:off - - - - (25) Dec 21 23:02:28 VERBOSE[12662] logger.c: 13 headers, 11 lines Dec 21 23:02:28 VERBOSE[12662] logger.c: Reliably Transmitting (no NAT) to 192.168.1.31:2158: INVITE sip:101@192.168.1.31:2158;line=gv8x1x75 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb From: "102" ;tag=as141708c8 To: Contact: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 22 Dec 2006 06:02:28 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 240 v=0 o=root 12356 12356 IN IP4 192.168.1.90 s=session c=IN IP4 192.168.1.90 t=0 0 m=audio 19388 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Dec 21 23:02:28 DEBUG[12662] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #11 Dec 21 23:02:28 VERBOSE[12662] logger.c: -- Called 101 Dec 21 23:02:28 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb From: "102" ;tag=as141708c8 To: ;tag=kdgvqby7sr Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 102 INVITE Contact: ;flow-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Content-Length: 0 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb (57) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 2: From: "102" ;tag=as141708c8 (49) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=kdgvqby7sr (60) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 5: CSeq: 102 INVITE (16) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 6: Contact: ;flow-id=1 (60) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 8: Allow-Events: talk, hold, refer (31) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: Header 10: (0) Dec 21 23:02:28 VERBOSE[12369] logger.c: --- (10 headers 0 lines) --- Dec 21 23:02:28 DEBUG[12369] chan_sip.c: = Found Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag Our tag: as141708c8 Dec 21 23:02:28 DEBUG[12369] chan_sip.c: *** SIP TIMER: Cancelling retransmission #11 - INVITE (got response) Dec 21 23:02:28 DEBUG[12369] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '7402796813ed40d119f62af64921d437@192.168.1.90' Request 102: Found Dec 21 23:02:28 DEBUG[12369] chan_sip.c: SIP response 180 to standard invite Dec 21 23:02:28 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:28 VERBOSE[12662] logger.c: -- SIP/101-093da990 is ringing Dec 21 23:02:28 DEBUG[12362] devicestate.c: Changing state for SIP/101 - state 6 (Ringing) Dec 21 23:02:28 VERBOSE[12662] logger.c: Transmitting (no NAT) to 192.168.1.34:2069: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-g6r15o67re4s;rport;received=192.168.1.34 From: ;tag=j811bts70y To: ;tag=as4151ed87 Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 21 23:02:28 DEBUG[12666] app_queue.c: Device 'SIP/101' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. Dec 21 23:02:28 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:29 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb From: "102" ;tag=as141708c8 To: ;tag=kdgvqby7sr Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 102 INVITE Contact: ;flow-id=1 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Content-Length: 0 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 0: SIP/2.0 180 Ringing (19) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb (57) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 2: From: "102" ;tag=as141708c8 (49) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=kdgvqby7sr (60) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 5: CSeq: 102 INVITE (16) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 6: Contact: ;flow-id=1 (60) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 7: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 8: Allow-Events: talk, hold, refer (31) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 10: (0) Dec 21 23:02:29 VERBOSE[12369] logger.c: --- (10 headers 0 lines) --- Dec 21 23:02:29 DEBUG[12369] chan_sip.c: = Found Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '7402796813ed40d119f62af64921d437@192.168.1.90' Request 102: Found Dec 21 23:02:29 DEBUG[12369] chan_sip.c: SIP response 180 to standard invite Dec 21 23:02:29 VERBOSE[12662] logger.c: -- SIP/101-093da990 is ringing Dec 21 23:02:29 DEBUG[12662] rtp.c: Got RTCP report of 52 bytes Dec 21 23:02:29 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb From: "102" ;tag=as141708c8 To: ;tag=kdgvqby7sr Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 102 INVITE Contact: ;flow-id=1 User-Agent: snom360/6.2.3 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Type: application/sdp Content-Length: 206 v=0 o=root 397487008 397487009 IN IP4 192.168.1.31 s=call c=IN IP4 192.168.1.31 t=0 0 m=audio 50568 RTP/AVP 0 101 a=rtpmap:0 pcmu/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 0: SIP/2.0 200 Ok (14) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK275b21eb (57) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 2: From: "102" ;tag=as141708c8 (49) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=kdgvqby7sr (60) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 5: CSeq: 102 INVITE (16) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 6: Contact: ;flow-id=1 (60) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 7: User-Agent: snom360/6.2.3 (25) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 9: Allow-Events: talk, hold, refer (31) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 10: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 11: Content-Type: application/sdp (29) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 12: Content-Length: 206 (19) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 13: (0) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: o=root 397487008 397487009 IN IP4 192.168.1.31 (46) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: c=IN IP4 192.168.1.31 (21) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: m=audio 50568 RTP/AVP 0 101 (27) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Line: a=sendrecv (10) Dec 21 23:02:29 VERBOSE[12369] logger.c: --- (13 headers 10 lines) --- Dec 21 23:02:29 DEBUG[12369] chan_sip.c: = Found Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Acked pending invite 102 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Stopping retransmission on '7402796813ed40d119f62af64921d437@192.168.1.90' of Request 102: Match Found Dec 21 23:02:29 DEBUG[12369] chan_sip.c: SIP response 200 to standard invite Dec 21 23:02:29 VERBOSE[12369] logger.c: Found RTP audio format 0 Dec 21 23:02:29 VERBOSE[12369] logger.c: Found RTP audio format 101 Dec 21 23:02:29 VERBOSE[12369] logger.c: Peer audio RTP is at port 192.168.1.31:50568 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Peer audio RTP is at port 192.168.1.31:50568 Dec 21 23:02:29 VERBOSE[12369] logger.c: Found description format pcmu Dec 21 23:02:29 VERBOSE[12369] logger.c: Found description format telephone-event Dec 21 23:02:29 VERBOSE[12369] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Dec 21 23:02:29 VERBOSE[12369] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: build_route: Contact hop: ;flow-id=1 Dec 21 23:02:29 VERBOSE[12369] logger.c: list_route: hop: Dec 21 23:02:29 VERBOSE[12369] logger.c: set_destination: Parsing for address/port to send to Dec 21 23:02:29 VERBOSE[12369] logger.c: set_destination: set destination to 192.168.1.31, port 2158 Dec 21 23:02:29 VERBOSE[12369] logger.c: Transmitting (no NAT) to 192.168.1.31:2158: ACK sip:101@192.168.1.31:2158;line=gv8x1x75 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.90:5060;branch=z9hG4bK1b31b519 From: "102" ;tag=as141708c8 To: ;tag=kdgvqby7sr Contact: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- Dec 21 23:02:29 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:29 VERBOSE[12662] logger.c: -- SIP/101-093da990 answered SIP/102-093d29a8 Dec 21 23:02:29 DEBUG[12362] devicestate.c: Changing state for SIP/101 - state 2 (In use) Dec 21 23:02:29 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:29 DEBUG[12662] chan_sip.c: sip_answer(SIP/102-093d29a8) Dec 21 23:02:29 DEBUG[12667] app_queue.c: Device 'SIP/101' changed to state '2' (In use) but we don't care because they're not a member of any queue. Dec 21 23:02:29 DEBUG[12362] chan_sip.c: Checking device state for peer 102 Dec 21 23:02:29 VERBOSE[12662] logger.c: We're at 192.168.1.90 port 17636 Dec 21 23:02:29 DEBUG[12362] channel.c: Avoiding initial deadlock for 'SIP/102-093d29a8' Dec 21 23:02:29 VERBOSE[12662] logger.c: Adding codec 0x4 (ulaw) to SDP Dec 21 23:02:29 VERBOSE[12662] logger.c: Adding codec 0x8 (alaw) to SDP Dec 21 23:02:29 VERBOSE[12662] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Dec 21 23:02:29 VERBOSE[12662] logger.c: Reliably Transmitting (no NAT) to 192.168.1.34:2069: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-g6r15o67re4s;rport;received=192.168.1.34 From: ;tag=j811bts70y To: ;tag=as4151ed87 Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 12356 12356 IN IP4 192.168.1.90 s=session c=IN IP4 192.168.1.90 t=0 0 m=audio 17636 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Dec 21 23:02:29 DEBUG[12662] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #12 Dec 21 23:02:29 DEBUG[12362] devicestate.c: Changing state for SIP/102 - state 2 (In use) Dec 21 23:02:29 DEBUG[12362] chan_sip.c: Checking device state for peer 102 Dec 21 23:02:29 DEBUG[12668] app_queue.c: Device 'SIP/102' changed to state '2' (In use) but we don't care because they're not a member of any queue. Dec 21 23:02:29 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.34:2069: ACK sip:101@192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-zywimigpcbbe;rport From: ;tag=j811bts70y To: ;tag=as4151ed87 Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE CSeq: 2 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 0: ACK sip:101@192.168.1.90 SIP/2.0 (32) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.34:2069;branch=z9hG4bK-zywimigpcbbe;rport (68) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=j811bts70y (43) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=as4151ed87 (52) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE (55) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 5: CSeq: 2 ACK (11) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Header 9: (0) Dec 21 23:02:29 VERBOSE[12369] logger.c: --- (9 headers 0 lines) --- Dec 21 23:02:29 DEBUG[12369] chan_sip.c: = No match Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Their Tag j811bts70y Our tag: as4151ed87 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 21 23:02:29 DEBUG[12369] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #12 Dec 21 23:02:29 DEBUG[12369] chan_sip.c: Stopping retransmission on '3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE' of Response 2: Match Found Dec 21 23:02:29 DEBUG[12662] rtp.c: Got RTCP report of 52 bytes Dec 21 23:02:29 DEBUG[12662] rtp.c: Ooh, format changed from unknown to ulaw Dec 21 23:02:29 DEBUG[12662] rtp.c: Ooh, format changed from unknown to ulaw Dec 21 23:02:33 DEBUG[12662] rtp.c: Sending dtmf: 42 (*), at 192.168.1.31 Dec 21 23:02:33 DEBUG[12662] channel.c: Got DTMF on channel (SIP/101-093da990) Dec 21 23:02:33 DEBUG[12662] channel.c: Bridge stops bridging channels SIP/102-093d29a8 and SIP/101-093da990 Dec 21 23:02:33 DEBUG[12662] res_features.c: Feature interpret: chan=SIP/102-093d29a8, peer=SIP/101-093da990, sense=2, features=2 Dec 21 23:02:33 DEBUG[12662] res_features.c: Set time limit to 500 Dec 21 23:02:34 DEBUG[12662] rtp.c: Sending dtmf: 51 (3), at 192.168.1.31 Dec 21 23:02:34 DEBUG[12662] channel.c: Got DTMF on channel (SIP/101-093da990) Dec 21 23:02:34 DEBUG[12662] channel.c: Bridge stops bridging channels SIP/102-093d29a8 and SIP/101-093da990 Dec 21 23:02:34 DEBUG[12662] res_features.c: Feature interpret: chan=SIP/102-093d29a8, peer=SIP/101-093da990, sense=2, features=2 Dec 21 23:02:34 DEBUG[12662] chan_sip.c: Bridged channel now on hold3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Started music on hold, class 'default', on SIP/102-093d29a8 Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Playing 'pbx-transfer' (language 'en') Dec 21 23:02:34 DEBUG[12669] channel.c: Generator got voice, switching to phase locked mode Dec 21 23:02:34 DEBUG[12669] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:34 DEBUG[12669] channel.c: Set channel SIP/102-093d29a8 to write format slin Dec 21 23:02:34 DEBUG[12669] res_musiconhold.c: SIP/102-093d29a8 Opened file 0 '/var/lib/asterisk/mohmp3/fpm-sunshine' Dec 21 23:02:34 DEBUG[12669] rtp.c: Difference is 1120, ms is 160 Dec 21 23:02:34 DEBUG[12662] rtp.c: Sending dtmf: 55 (7), at 192.168.1.31 Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:34 DEBUG[12662] channel.c: Set channel SIP/101-093da990 to write format slin Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:34 DEBUG[12662] rtp.c: Sending dtmf: 48 (0), at 192.168.1.31 Dec 21 23:02:34 DEBUG[12662] channel.c: Set channel SIP/101-093da990 to write format ulaw Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Stopped music on hold on SIP/102-093d29a8 Dec 21 23:02:34 DEBUG[12662] channel.c: Set channel SIP/102-093d29a8 to write format ulaw Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:34 DEBUG[12662] chan_sip.c: Bridged channel is back from hold, let's talk! : 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:34 DEBUG[12662] chan_sip.c: Bridged channel now on hold3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Started music on hold, class 'default', on SIP/102-093d29a8 Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:34 VERBOSE[12662] logger.c: == Parked SIP/102-093d29a8 on 71. Will timeout back to extension [custom-100users] 101, 1 in 10 seconds Dec 21 23:02:34 DEBUG[12662] pbx.c: Added extension '71' priority 1 to parkedcalls Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Added extension '71' priority 1 to parkedcalls Dec 21 23:02:34 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:34 VERBOSE[12662] logger.c: -- Playing 'digits/7' (language 'en') Dec 21 23:02:34 DEBUG[12364] channel.c: Generator got voice, switching to phase locked mode Dec 21 23:02:34 DEBUG[12364] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:34 DEBUG[12364] channel.c: Set channel SIP/102-093d29a8 to write format slin Dec 21 23:02:34 DEBUG[12364] res_musiconhold.c: SIP/102-093d29a8 Opened file 0 '/var/lib/asterisk/mohmp3/fpm-sunshine' Dec 21 23:02:34 DEBUG[12662] rtp.c: Got RTCP report of 52 bytes Dec 21 23:02:34 DEBUG[12364] rtp.c: Got RTCP report of 52 bytes Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: INVITE sip:102@192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-zetgwq365hih;rport From: ;tag=kdgvqby7sr To: "102" ;tag=as141708c8 Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 1 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom360/6.2.3 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 361 v=0 o=root 397487008 397487010 IN IP4 192.168.1.31 s=call c=IN IP4 0.0.0.0 t=0 0 m=audio 50568 RTP/AVP 0 8 9 2 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendonly Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: INVITE sip:102@192.168.1.90 SIP/2.0 (35) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-zetgwq365hih;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=kdgvqby7sr (62) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: "102" ;tag=as141708c8 (47) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 INVITE (14) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: P-Key-Flags: resolution="31x13", keys="4" (41) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: User-Agent: snom360/6.2.3 (25) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 10: Accept: application/sdp (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 12: Allow-Events: talk, hold, refer (31) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 15: Min-SE: 90 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 16: Content-Type: application/sdp (29) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 17: Content-Length: 361 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 18: (0) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: o=root 397487008 397487010 IN IP4 192.168.1.31 (46) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: c=IN IP4 0.0.0.0 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: m=audio 50568 RTP/AVP 0 8 9 2 3 18 4 101 (40) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=ptime:20 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=sendonly (10) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (18 headers 17 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = Found Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Begin: parsing SIP "Supported: timer, 100rel, replaces, callerid" Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -timer- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: timer Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -100rel- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: 100rel Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -replaces- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: replaces Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -callerid- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found no match for SIP option: callerid (Please file bug report!) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: * SIP extension value: 7 for call 7402796813ed40d119f62af64921d437@192.168.1.90 Dec 21 23:02:35 VERBOSE[12369] logger.c: Using INVITE request as basis request - 7402796813ed40d119f62af64921d437@192.168.1.90 Dec 21 23:02:35 VERBOSE[12369] logger.c: Sending to 192.168.1.31 : 2158 (NAT) Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 0 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 8 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 9 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 2 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 3 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 18 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 4 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 101 Dec 21 23:02:35 VERBOSE[12369] logger.c: Peer audio RTP is at port 0.0.0.0:50568 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Peer audio RTP is at port 0.0.0.0:50568 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format pcmu Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format pcma Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g722 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g726-32 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format gsm Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g729 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g723 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format telephone-event Dec 21 23:02:35 VERBOSE[12369] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x11f (g723|gsm|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0xc (ulaw|alaw) Dec 21 23:02:35 VERBOSE[12369] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Got a SIP re-invite for call 7402796813ed40d119f62af64921d437@192.168.1.90 Dec 21 23:02:35 VERBOSE[12369] logger.c: We're at 192.168.1.90 port 19388 Dec 21 23:02:35 VERBOSE[12369] logger.c: Adding codec 0x4 (ulaw) to SDP Dec 21 23:02:35 VERBOSE[12369] logger.c: Adding codec 0x8 (alaw) to SDP Dec 21 23:02:35 VERBOSE[12369] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Dec 21 23:02:35 VERBOSE[12369] logger.c: Reliably Transmitting (NAT) to 192.168.1.31:2158: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-zetgwq365hih;rport;received=192.168.1.31 From: ;tag=kdgvqby7sr To: "102" ;tag=as141708c8 Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 12356 12357 IN IP4 192.168.1.90 s=session c=IN IP4 192.168.1.90 t=0 0 m=audio 19388 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #13 Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 51 sample intervals Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 160 sample intervals Dec 21 23:02:35 VERBOSE[12662] logger.c: -- Playing 'digits/1' (language 'en') Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: INVITE sip:71@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-a87raa1pw0n9;rport From: "101" ;tag=wo7xmzm0ll To: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 1 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom360/6.2.3 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 368 v=0 o=root 1455741242 1455741242 IN IP4 192.168.1.31 s=call c=IN IP4 192.168.1.31 t=0 0 m=audio 55792 RTP/AVP 0 8 9 2 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: INVITE sip:71@192.168.1.90;user=phone SIP/2.0 (45) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-a87raa1pw0n9;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: "101" ;tag=wo7xmzm0ll (49) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: (36) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 (55) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 INVITE (14) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: P-Key-Flags: resolution="31x13", keys="4" (41) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: User-Agent: snom360/6.2.3 (25) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 10: Accept: application/sdp (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 12: Allow-Events: talk, hold, refer (31) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 15: Min-SE: 90 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 16: Content-Type: application/sdp (29) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 17: Content-Length: 368 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 18: (0) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: o=root 1455741242 1455741242 IN IP4 192.168.1.31 (48) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: c=IN IP4 192.168.1.31 (21) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: m=audio 55792 RTP/AVP 0 8 9 2 3 18 4 101 (40) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=ptime:20 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=sendrecv (10) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (18 headers 17 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = No match Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = No match Their Call ID: 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Their Tag j811bts70y Our tag: as4151ed87 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Allocating new SIP dialog for 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 - INVITE (With RTP) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Begin: parsing SIP "Supported: timer, 100rel, replaces, callerid" Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -timer- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: timer Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -100rel- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: 100rel Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -replaces- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Matched SIP option: replaces Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found SIP option: -callerid- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Found no match for SIP option: callerid (Please file bug report!) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: * SIP extension value: 7 for call 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Dec 21 23:02:35 VERBOSE[12369] logger.c: Using INVITE request as basis request - 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Dec 21 23:02:35 VERBOSE[12369] logger.c: Sending to 192.168.1.31 : 2158 (NAT) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Setting NAT on RTP to 0 Dec 21 23:02:35 VERBOSE[12369] logger.c: Reliably Transmitting (no NAT) to 192.168.1.31:2158: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-a87raa1pw0n9;rport;received=192.168.1.31 From: "101" ;tag=wo7xmzm0ll To: ;tag=as7cf7f542 Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="65868284" Content-Length: 0 --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #14 Dec 21 23:02:35 VERBOSE[12369] logger.c: Scheduling destruction of call '3c2671ca5573-ipuej6gijqfl@snom360-000413235410' in 15000 ms Dec 21 23:02:35 VERBOSE[12369] logger.c: Found user '101' Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: ACK sip:102@192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-gjj4qplsq6ky;rport From: ;tag=kdgvqby7sr To: "102" ;tag=as141708c8 Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 CSeq: 1 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: ACK sip:102@192.168.1.90 SIP/2.0 (32) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-gjj4qplsq6ky;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: ;tag=kdgvqby7sr (62) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: "102" ;tag=as141708c8 (47) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 7402796813ed40d119f62af64921d437@192.168.1.90 (54) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 ACK (11) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: (0) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (9 headers 0 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = No match Their Call ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Their Tag wo7xmzm0ll Our tag: as7cf7f542 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = Found Their Call ID: 7402796813ed40d119f62af64921d437@192.168.1.90 Their Tag kdgvqby7sr Our tag: as141708c8 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 21 23:02:35 DEBUG[12369] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #13 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Stopping retransmission on '7402796813ed40d119f62af64921d437@192.168.1.90' of Response 1: Match Found Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: ACK sip:71@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-a87raa1pw0n9;rport From: "101" ;tag=wo7xmzm0ll To: ;tag=as7cf7f542 Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 1 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: ACK sip:71@192.168.1.90;user=phone SIP/2.0 (42) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-a87raa1pw0n9;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: "101" ;tag=wo7xmzm0ll (49) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=as7cf7f542 (51) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 (55) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 1 ACK (11) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: (0) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (9 headers 0 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Their Tag wo7xmzm0ll Our tag: as7cf7f542 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 21 23:02:35 DEBUG[12369] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #14 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Stopping retransmission on '3c2671ca5573-ipuej6gijqfl@snom360-000413235410' of Response 1: Match Found Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: INVITE sip:71@192.168.1.90;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-2yxpf6apeh08;rport From: "101" ;tag=wo7xmzm0ll To: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 2 INVITE Max-Forwards: 70 Contact: ;flow-id=1 P-Key-Flags: resolution="31x13", keys="4" User-Agent: snom360/6.2.3 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Session-Expires: 3600;refresher=uas Min-SE: 90 Proxy-Authorization: Digest username="101",realm="asterisk",nonce="65868284",uri="sip:71@192.168.1.90;user=phone",response="6b9dc8a1a090ef8e716d0eec9e113e9b",algorithm=md5 Content-Type: application/sdp Content-Length: 368 v=0 o=root 1455741242 1455741242 IN IP4 192.168.1.31 s=call c=IN IP4 192.168.1.31 t=0 0 m=audio 55792 RTP/AVP 0 8 9 2 3 18 4 101 a=rtpmap:0 pcmu/8000 a=rtpmap:8 pcma/8000 a=rtpmap:9 g722/8000 a=rtpmap:2 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: INVITE sip:71@192.168.1.90;user=phone SIP/2.0 (45) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-2yxpf6apeh08;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: "101" ;tag=wo7xmzm0ll (49) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: (36) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 (55) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 2 INVITE (14) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: P-Key-Flags: resolution="31x13", keys="4" (41) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: User-Agent: snom360/6.2.3 (25) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 10: Accept: application/sdp (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 11: Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO (88) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 12: Allow-Events: talk, hold, refer (31) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 13: Supported: timer, 100rel, replaces, callerid (44) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 14: Session-Expires: 3600;refresher=uas (35) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 15: Min-SE: 90 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 16: Proxy-Authorization: Digest username="101",realm="asterisk",nonce="65868284",uri="sip:71@192.168.1.90;user=phone",response="6b9dc8a1a090ef8e716d0eec9e113e9b",algorithm=md5 (171) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 17: Content-Type: application/sdp (29) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 18: Content-Length: 368 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 19: (0) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: v=0 (3) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: o=root 1455741242 1455741242 IN IP4 192.168.1.31 (48) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: s=call (6) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: c=IN IP4 192.168.1.31 (21) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: t=0 0 (5) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: m=audio 55792 RTP/AVP 0 8 9 2 3 18 4 101 (40) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:8 pcma/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:9 g722/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:2 g726-32/8000 (23) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:4 g723/8000 (20) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=fmtp:101 0-16 (15) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=ptime:20 (10) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Line: a=sendrecv (10) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (19 headers 17 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Their Tag wo7xmzm0ll Our tag: as7cf7f542 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 21 23:02:35 VERBOSE[12369] logger.c: Using INVITE request as basis request - 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Dec 21 23:02:35 VERBOSE[12369] logger.c: Sending to 192.168.1.31 : 2158 (NAT) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Setting NAT on RTP to 0 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found user '101' Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 0 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 8 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 9 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 2 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 3 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 18 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 4 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found RTP audio format 101 Dec 21 23:02:35 VERBOSE[12369] logger.c: Peer audio RTP is at port 192.168.1.31:55792 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Peer audio RTP is at port 192.168.1.31:55792 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format pcmu Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format pcma Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g722 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g726-32 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format gsm Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g729 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format g723 Dec 21 23:02:35 VERBOSE[12369] logger.c: Found description format telephone-event Dec 21 23:02:35 VERBOSE[12369] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x11f (g723|gsm|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0xc (ulaw|alaw) Dec 21 23:02:35 VERBOSE[12369] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Checking SIP call limits for device 101 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Updating call counter for incoming call Dec 21 23:02:35 VERBOSE[12369] logger.c: Looking for 71 in custom-100users (domain 192.168.1.90;user=phone) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: build_route: Contact hop: ;flow-id=1 Dec 21 23:02:35 VERBOSE[12369] logger.c: list_route: hop: Dec 21 23:02:35 VERBOSE[12369] logger.c: Transmitting (no NAT) to 192.168.1.31:2158: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-2yxpf6apeh08;rport;received=192.168.1.31 From: "101" ;tag=wo7xmzm0ll To: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 21 23:02:35 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:35 DEBUG[12670] pbx.c: Launching 'ParkedCall' Dec 21 23:02:35 DEBUG[12362] devicestate.c: Changing state for SIP/101 - state 2 (In use) Dec 21 23:02:35 VERBOSE[12670] logger.c: -- Executing ParkedCall("SIP/101-093ed128", "71") in new stack Dec 21 23:02:35 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:35 DEBUG[12671] app_queue.c: Device 'SIP/101' changed to state '2' (In use) but we don't care because they're not a member of any queue. Dec 21 23:02:35 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:35 DEBUG[12362] channel.c: Avoiding initial deadlock for 'SIP/101-093ed128' Dec 21 23:02:35 DEBUG[12670] chan_sip.c: sip_answer(SIP/101-093ed128) Dec 21 23:02:35 VERBOSE[12670] logger.c: We're at 192.168.1.90 port 13142 Dec 21 23:02:35 VERBOSE[12670] logger.c: Adding codec 0x4 (ulaw) to SDP Dec 21 23:02:35 VERBOSE[12670] logger.c: Adding codec 0x8 (alaw) to SDP Dec 21 23:02:35 VERBOSE[12670] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Dec 21 23:02:35 VERBOSE[12670] logger.c: Reliably Transmitting (no NAT) to 192.168.1.31:2158: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-2yxpf6apeh08;rport;received=192.168.1.31 From: "101" ;tag=wo7xmzm0ll To: ;tag=as318c06aa Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 12356 12356 IN IP4 192.168.1.90 s=session c=IN IP4 192.168.1.90 t=0 0 m=audio 13142 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Dec 21 23:02:35 DEBUG[12670] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #16 Dec 21 23:02:35 VERBOSE[12670] logger.c: -- Stopped music on hold on SIP/102-093d29a8 Dec 21 23:02:35 DEBUG[12670] channel.c: Set channel SIP/102-093d29a8 to write format ulaw Dec 21 23:02:35 DEBUG[12670] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:35 DEBUG[12670] chan_sip.c: Bridged channel is back from hold, let's talk! : 3c27b00e5573-cajwcaj96u3c@snom300-0004132516FE Dec 21 23:02:35 VERBOSE[12670] logger.c: -- Channel SIP/101-093ed128 connected to parked call 71 Dec 21 23:02:35 VERBOSE[12670] logger.c: -- Attempting native bridge of SIP/101-093ed128 and SIP/102-093d29a8 Dec 21 23:02:35 DEBUG[12362] devicestate.c: Changing state for SIP/101 - state 2 (In use) Dec 21 23:02:35 DEBUG[12362] chan_sip.c: Checking device state for peer 101 Dec 21 23:02:35 DEBUG[12672] app_queue.c: Device 'SIP/101' changed to state '2' (In use) but we don't care because they're not a member of any queue. Dec 21 23:02:35 DEBUG[12670] rtp.c: Ooh, format changed from unknown to ulaw Dec 21 23:02:35 VERBOSE[12369] logger.c: <-- SIP read from 192.168.1.31:2158: ACK sip:71@192.168.1.90 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-lh7m51mokqyt;rport From: "101" ;tag=wo7xmzm0ll To: ;tag=as318c06aa Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 CSeq: 2 ACK Max-Forwards: 70 Contact: ;flow-id=1 Content-Length: 0 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 0: ACK sip:71@192.168.1.90 SIP/2.0 (31) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.31:2158;branch=z9hG4bK-lh7m51mokqyt;rport (68) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 2: From: "101" ;tag=wo7xmzm0ll (49) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 3: To: ;tag=as318c06aa (51) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 4: Call-ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 (55) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 5: CSeq: 2 ACK (11) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 7: Contact: ;flow-id=1 (60) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Header 9: (0) Dec 21 23:02:35 VERBOSE[12369] logger.c: --- (9 headers 0 lines) --- Dec 21 23:02:35 DEBUG[12369] chan_sip.c: = Found Their Call ID: 3c2671ca5573-ipuej6gijqfl@snom360-000413235410 Their Tag wo7xmzm0ll Our tag: as318c06aa Dec 21 23:02:35 DEBUG[12369] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 21 23:02:35 DEBUG[12369] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #16 Dec 21 23:02:35 DEBUG[12369] chan_sip.c: Stopping retransmission on '3c2671ca5573-ipuej6gijqfl@snom360-000413235410' of Response 2: Match Found Dec 21 23:02:35 DEBUG[12670] rtp.c: Got RTCP report of 52 bytes Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 46 sample intervals Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals Dec 21 23:02:35 DEBUG[12662] channel.c: Scheduling timer at 0 sample intervals