Sep 4 13:06:01 VERBOSE[30937] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log Sep 4 13:06:01 DEBUG[30937] config.c: No file to parse: /etc/asterisk/dnsmgr.conf Sep 4 13:06:01 VERBOSE[30937] logger.c: Asterisk Dynamic Loader loading preload modules: Sep 4 13:06:01 DEBUG[30937] config.c: Parsing /etc/asterisk/modules.conf Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Ping Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Events Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Logoff Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Hangup Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Status Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Setvar Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Getvar Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Redirect Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Originate Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action Command Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action ExtensionState Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action AbsoluteTimeout Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action MailboxStatus Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action MailboxCount Sep 4 13:06:01 VERBOSE[30937] logger.c: == Manager registered action ListCommands Sep 4 13:06:01 DEBUG[30937] config.c: Parsing /etc/asterisk/manager.conf Sep 4 13:06:01 DEBUG[30937] config.c: No file to parse: /etc/asterisk/manager_custom.conf Sep 4 13:06:01 VERBOSE[30937] logger.c: Asterisk Management interface listening on port 5038 Sep 4 13:06:01 DEBUG[30937] config.c: No file to parse: /etc/asterisk/cdr.conf Sep 4 13:06:01 NOTICE[30937] cdr.c: CDR simple logging enabled. Sep 4 13:06:01 DEBUG[30937] config.c: No file to parse: /etc/asterisk/rtp.conf Sep 4 13:06:01 VERBOSE[30937] logger.c: == RTP Allocating from port range 5000 -> 31000 Sep 4 13:06:01 VERBOSE[30937] logger.c: Asterisk PBX Core Initializing Sep 4 13:06:01 VERBOSE[30937] logger.c: Registering builtin applications: Sep 4 13:06:01 VERBOSE[30937] logger.c: [AbsoluteTimeout] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'AbsoluteTimeout' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Answer] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Answer' Sep 4 13:06:01 VERBOSE[30937] logger.c: [BackGround] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'BackGround' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Busy] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Busy' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Congestion] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Congestion' Sep 4 13:06:01 VERBOSE[30937] logger.c: [DigitTimeout] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'DigitTimeout' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Goto] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Goto' Sep 4 13:06:01 VERBOSE[30937] logger.c: [GotoIf] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'GotoIf' Sep 4 13:06:01 VERBOSE[30937] logger.c: [GotoIfTime] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'GotoIfTime' Sep 4 13:06:01 VERBOSE[30937] logger.c: [ExecIfTime] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'ExecIfTime' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Hangup] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Hangup' Sep 4 13:06:01 VERBOSE[30937] logger.c: [NoOp] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'NoOp' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Prefix] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Prefix' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Progress] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Progress' Sep 4 13:06:01 VERBOSE[30937] logger.c: [ResetCDR] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'ResetCDR' Sep 4 13:06:01 VERBOSE[30937] logger.c: [ResponseTimeout] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'ResponseTimeout' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Ringing] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Ringing' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SayNumber] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SayNumber' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SayDigits] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SayDigits' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SayAlpha] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SayAlpha' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SayPhonetic] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SayPhonetic' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SetAccount] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SetAccount' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SetAMAFlags] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SetAMAFlags' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SetGlobalVar] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SetGlobalVar' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SetLanguage] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SetLanguage' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Set] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Set' Sep 4 13:06:01 VERBOSE[30937] logger.c: [SetVar] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'SetVar' Sep 4 13:06:01 VERBOSE[30937] logger.c: [ImportVar] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'ImportVar' Sep 4 13:06:01 VERBOSE[30937] logger.c: [StripMSD] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'StripMSD' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Suffix] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Suffix' Sep 4 13:06:01 VERBOSE[30937] logger.c: [Wait] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'Wait' Sep 4 13:06:01 VERBOSE[30937] logger.c: [WaitExten] Sep 4 13:06:01 VERBOSE[30937] logger.c: == Registered application 'WaitExten' Sep 4 13:06:01 VERBOSE[30937] logger.c: Asterisk Dynamic Loader Starting: Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/modules.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_realtime.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_realtime.so] => (Realtime Data Lookup/Rewrite) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'RealTimeUpdate' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'RealTime' Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_odbc.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_odbc.so] => (ODBC Resource) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/res_odbc.conf Sep 4 13:06:02 NOTICE[30937] res_odbc.c: registered database handle 'asterisk' dsn->[default] Sep 4 13:06:02 NOTICE[30937] res_odbc.c: Connecting asterisk Sep 4 13:06:02 NOTICE[30937] res_odbc.c: res_odbc: Connected to asterisk [default] Sep 4 13:06:02 NOTICE[30937] res_odbc.c: res_odbc loaded. Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem.so] => (Generic Voice Modem Driver) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/modem.conf Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Modem' (Generic Voice Modem Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Modem' (Generic Voice Modem Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_musiconhold.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_musiconhold.so] => (Music On Hold Resource) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MusicOnHold' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'WaitMusicOnHold' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetMusicOnHold' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'StartMusicOnHold' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'StopMusicOnHold' Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/musiconhold.conf Sep 4 13:06:02 WARNING[30937] res_musiconhold.c: The old musiconhold.conf syntax has been deprecated! Please refer to the sample configuration for information on the new syntax. Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_adsi.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_adsi.so] => (ADSI Resource) Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/adsi.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_features.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_features.so] => (Call Features Resource) Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/features.conf Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/parking.conf Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'parkedcalls' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '700' priority 1 to parkedcalls Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ParkedCall' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Park' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ParkedCalls Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_crypto.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_crypto.so] => (Cryptographic Digital Signatures) Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Loaded PUBLIC key 'iaxtel' Sep 4 13:06:02 DEBUG[30937] res_crypto.c: Key 'iaxtel' loaded OK Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Loaded PUBLIC key 'freeworlddialup' Sep 4 13:06:02 DEBUG[30937] res_crypto.c: Key 'freeworlddialup' loaded OK Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_indications.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_indications.so] => (Indications Configuration) Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/indications.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'PlayTones' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'StopPlayTones' Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_monitor.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_monitor.so] => (Call Monitoring Resource) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Monitor' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'StopMonitor' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ChangeMonitor' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action Monitor Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action StopMonitor Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ChangeMonitor Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_agi.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_agi.so] => (Asterisk Gateway Interface (AGI)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DeadAGI' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'EAGI' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AGI' Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_config_odbc.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_config_odbc.so] => (ODBC Configuration) Sep 4 13:06:02 NOTICE[30937] config.c: Registered Config Engine odbc Sep 4 13:06:02 VERBOSE[30937] logger.c: res_config_odbc loaded. Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_config_mysql.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [res_config_mysql.so] => (MySQL RealTime Configuration Driver) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/res_mysql.conf Sep 4 13:06:02 WARNING[30937] res_config_mysql.c: MySQL RealTime: No database port found, using 3306 as default. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime Host: localhost Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime Port: 3306 Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime User: asterisk Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime Password: asterisk Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Successfully connected to database. Sep 4 13:06:02 NOTICE[30937] config.c: Registered Config Engine mysql Sep 4 13:06:02 VERBOSE[30937] logger.c: MySQL RealTime driver loaded. Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_sip.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_sip.so] => (Session Initiation Protocol (SIP)) Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'SIP' (Session Initiation Protocol (SIP)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'SIP' (Session Initiation Protocol (SIP)) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='sip.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 30 rows. Sep 4 13:06:02 DEBUG[30937] acl.c: 10.1.1.1/255.255.255.0/255.255.255.0 appended to acl for peer Sep 4 13:06:02 VERBOSE[30937] logger.c: == SIP Listening on 10.1.1.12:5060 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Using TOS bits 24 Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='sip_notify.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 2 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SIPDtmfMode' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SIPAddHeader' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SIPGetHeader' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function SIP_HEADER Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function SIPPEER Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function SIPCHANINFO Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action SIPpeers Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action SIPshowpeer Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_aopen.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_aopen.so] => (A/Open (Rockwell Chipset) ITU-2 VoiceModem Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_bestdata.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_bestdata.so] => (BestData (Conexant V.90 Chipset) VoiceModem Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_agent.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_agent.so] => (Agent Proxy Channel) Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Agent' (Call Agent Proxy Channel) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Agent' (Call Agent Proxy Channel) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AgentLogin' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AgentCallbackLogin' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AgentMonitorOutgoing' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action Agents Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action AgentLogoff Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action AgentCallbackLogin Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/agents.conf Sep 4 13:06:02 NOTICE[30937] chan_agent.c: No agent configuration found -- agent support disabled Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_mgcp.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_mgcp.so] => (Media Gateway Control Protocol (MGCP)) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='mgcp.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 6 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: == MGCP Listening on 0.0.0.0:2727 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Using TOS bits 0 Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'MGCP' (Media Gateway Control Protocol (MGCP)) Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_iax2.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_iax2.so] => (Inter Asterisk eXchange (Ver 2)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function IAXPEER Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'IAX2Provision' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action IAXpeers Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action IAXnetstats Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='iax.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 10 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: == Using TOS bits 24 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Binding IAX2 to '0.0.0.0:4569' Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'IAX2' (Inter Asterisk eXchange Driver (Ver 2)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == IAX Ready and Listening Sep 4 13:06:02 VERBOSE[30937] logger.c: == Loaded firmware 'iaxy.bin' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='iaxprov.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 7 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Loaded provisioning template 'default' Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_local.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_local.so] => (Local Proxy Channel) Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Local' (Local Proxy Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Local' (Local Proxy Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_skinny.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_skinny.so] => (Skinny Client Control Protocol (Skinny)) Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/skinny.conf Sep 4 13:06:02 NOTICE[30937] chan_skinny.c: Unable to load config skinny.conf, Skinny disabled Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Skinny' (Skinny Client Control Protocol (Skinny)) Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_features.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_features.so] => (Feature Proxy Channel) Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Feature' (Feature Proxy Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Feature' (Feature Proxy Channel Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [skipping chan_oss.so] Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_i4l.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_modem_i4l.so] => (ISDN4Linux Emulated Modem Driver) Sep 4 13:06:02 VERBOSE[30937] logger.c: [skipping chan_phone.so] Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_zap.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [chan_zap.so] => (Zapata Telephony w/PRI) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/zapata.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Automatically generated pseudo channel Sep 4 13:06:02 DEBUG[30937] channel.c: Registered handler for 'Zap' (Zapata Telephony Driver w/PRI) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered channel type 'Zap' (Zapata Telephony Driver w/PRI) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapTransfer Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapHangup Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapDialOffhook Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapDNDon Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapDNDoff Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action ZapShowChannels Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_config.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_config.so] => (Text Extension Configuration) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_custom.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_realtime.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_macro.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_apps.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_callforward.conf Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/extensions_followme.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'VM_PREFIX' to '*' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'RINGTIMER' to '15' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'REGTIME' to '7:55-17:05' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'REGDAYS' to 'mon-fri' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'RECORDEXTEN' to '""' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'PARKNOTIFY' to 'SIP/200' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'OPERATOR' to '250' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'NULL' to '""' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'IN_OVERRIDE' to 'forcereghours' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'INCOMING' to 'group-all' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'FAX_RX_EMAIL' to 'fax@mydomain.com' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'FAX_RX' to 'system' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'FAX' to '' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'DIRECTORY_OPTS' to '' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'DIRECTORY' to 'last' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'DIAL_OPTIONS' to 'tr' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'DIALOUTIDS' to '1' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'CALLFILENAME' to '""' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'AFTER_INCOMING' to '' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'OUTCID' to '9712448050' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'OUT' to 'SIP/19712448050' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Setting global variable 'Local' to 'SIP' Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'sales' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to sales Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'support' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to support Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'from-pstn' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to from-pstn Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 1 to from-pstn Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'T' priority 1 to from-pstn Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including switch 'Realtime/from-pstn-rt@extensions' in context 'from-pstn' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including switch 'Realtime/internal-users-rt@extensions' in context 'from-pstn' Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'fromvm' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'from-pstn' in context 'fromvm' Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'invalid' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including switch 'Realtime/from-internal-rt@extensions' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including switch 'Realtime/features@extensions' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including switch 'Realtime/internal-users-rt@extensions' in context 'from-internal' Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to include switch 'Realtime/from-internal-rt@extensions' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-calltrace' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'parkedcalls' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-voicemail' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-dnd' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-callforward' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-followme' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'ext-meetme' in context 'from-internal' Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to include context 'app-callforward' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-directory' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'app-displayvar' in context 'from-internal' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'portable-extensions' in context 'from-internal' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*77' priority 1 to from-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-stdexten' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-NOANSWER' priority 1 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-NOANSWER' priority 2 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-BUSY' priority 1 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-BUSY' priority 2 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_s-.' priority 1 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'a' priority 1 to macro-stdexten Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-goto-context-exten-prio' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-goto-context-exten-prio Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-dial' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 10 to macro-dial Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-place_local_or_pstn_call' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'local' priority 1 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'local' priority 2 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'local' priority 3 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'pstn' priority 1 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'pstn' priority 2 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'pstn' priority 3 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-place_local_or_pstn_call Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-calltrace' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 8 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 9 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 10 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 11 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 12 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 13 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 14 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '1' priority 1 to macro-calltrace Sep 4 13:06:02 WARNING[30937] pbx.c: Unable to register extension '1', priority 1 in 'macro-calltrace', already in use Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to register extension at line 69 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'i' priority 1 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'i' priority 2 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 1 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 2 to macro-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-vm' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'o' priority 1 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'o' priority 2 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'a' priority 1 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'a' priority 2 to macro-vm Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-fixcid' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-fixcid Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-rg-group' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-rg-group Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-rg-group Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-rg-group Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-rg-group Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-rg-group Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-outisbusy' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-outisbusy Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-outisbusy Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-outisbusy Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-hangupcall' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-hangupcall Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-hangupcall Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-hangupcall Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-faxreceive' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-faxreceive Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-faxreceive Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-faxreceive Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 103 to macro-faxreceive Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 104 to macro-faxreceive Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-dialout' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 8 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 9 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 109 to macro-dialout Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-dialout-default' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'nocidnum' priority 1 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'nocidnum' priority 2 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'nocidname' priority 1 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'nocidname' priority 2 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-dialout-default Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-dialout-default-disabled' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 8 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 9 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 10 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 108 to macro-dialout-default-disabled Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-agent-add' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-agent-add Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-agent-del' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-agent-del Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-agent-del Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-agent-del Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-agent-del Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-agent-del Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-changevmpass' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-changevmpass Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-directory' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '#' priority 1 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '#' priority 2 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '#' priority 3 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '#' priority 4 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*411' priority 1 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*411' priority 2 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*411' priority 3 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*411' priority 4 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*411' priority 5 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'h' priority 1 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'o' priority 1 to app-directory Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-dnd' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*78' priority 1 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*78' priority 2 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*78' priority 3 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*78' priority 4 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*78' priority 5 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*79' priority 1 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*79' priority 2 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*79' priority 3 to app-dnd Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*79' priority 4 to app-dnd Sep 4 13:06:02 WARNING[30937] pbx.c: Unable to register extension '*79', priority 4 in 'app-dnd', already in use Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to register extension at line 29 Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-voicemail' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*98' priority 1 to app-voicemail Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*69' priority 2 to app-voicemail Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*98' priority 3 to app-voicemail Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*98' priority 4 to app-voicemail Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-calltrace' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*69' priority 1 to app-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*69' priority 2 to app-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*69' priority 3 to app-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*69' priority 4 to app-calltrace Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'portable-extensions' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '6900' priority 1 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 1 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 2 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 3 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 4 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 5 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_X.' priority 104 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 1 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 2 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 3 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 4 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 5 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'testvm' priority 102 to portable-extensions Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'portable-extensions-6900' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 8 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 9 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 105 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 106 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'deactivate' priority 1 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'deactivate' priority 2 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'deactivate' priority 3 to portable-extensions-6900 Sep 4 13:06:02 WARNING[30937] pbx.c: Unable to register extension 'deactivate', priority 3 in 'portable-extensions-6900', already in use Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to register extension at line 78 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'deactivate' priority 103 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'deactivate' priority 104 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 1 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 3 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 4 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 5 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 6 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 7 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'gather' priority 8 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'commit' priority 1 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'commit' priority 2 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'commit' priority 3 to portable-extensions-6900 Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-cancel_all' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 8 to macro-cancel_all Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-get-exten-advance' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 1 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 2 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 't' priority 3 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'T' priority 1 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'T' priority 2 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'T' priority 3 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'i' priority 1 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'i' priority 2 to macro-get-exten-advance Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-callforward' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 1 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 2 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 3 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 4 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 5 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 6 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 7 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 8 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 9 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*72.' priority 10 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 1 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 2 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 3 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 4 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 5 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 6 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 7 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 8 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 9 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 10 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 11 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 12 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 13 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*72' priority 14 to app-callforward Sep 4 13:06:02 WARNING[30937] pbx.c: Unable to register extension '*72', priority 14 in 'app-callforward', already in use Sep 4 13:06:02 WARNING[30937] pbx_config.c: Unable to register extension at line 58 Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*73.' priority 1 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*73' priority 1 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 1 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 2 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 3 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 4 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 5 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 6 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 7 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 8 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 9 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*90.' priority 10 to app-callforward Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-dial-internal' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 5 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 6 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 7 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob' priority 4 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-busy' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-busy' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-busy' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-local' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-local' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-local' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-pstn' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cfob-pstn' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf-pstn' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf-pstn' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf-local' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf-local' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'cf-local' priority 3 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'fm' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'fm' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'hunt' priority 1 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'hunt' priority 2 to macro-dial-internal Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'macro-followme' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-NOANSWER' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's-BUSY' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 2 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 3 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 4 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 5 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 6 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 7 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 8 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 9 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 10 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'followme' priority 200 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'fm-NOANSWER' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'fm-BUSY' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_s-.' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'a' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'voicemail_noanswer' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'voicemail_noanswer' priority 2 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'voicemail_busy' priority 1 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'voicemail_busy' priority 2 to macro-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'app-followme' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 1 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 2 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 3 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 4 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 5 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 6 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 7 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 106 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 107 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 108 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 109 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 208 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 209 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 210 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 211 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 212 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 213 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 214 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*22' priority 215 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 1 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 2 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 3 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 4 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 5 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 6 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 7 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 8 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 9 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 10 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 11 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 12 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*21.' priority 13 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 1 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 2 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 3 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 4 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 5 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 6 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 7 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 8 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 9 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 10 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*21' priority 11 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 1 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 2 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 3 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 4 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 5 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_*23.' priority 6 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 1 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 2 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 3 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 4 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 5 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '*23' priority 6 to app-followme Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'ext-meetme' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_8XX' priority 1 to ext-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_8XX' priority 2 to ext-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_8XX' priority 3 to ext-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_8XX' priority 4 to ext-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension '_8XX' priority 5 to ext-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'default' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to default Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to default Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'from-internal' in context 'default' Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'invalid' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to invalid Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 2 to invalid Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 3 to invalid Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 4 to invalid Sep 4 13:06:02 DEBUG[30937] pbx.c: Registered context 'custom-meetme' Sep 4 13:06:02 VERBOSE[30937] logger.c: -- Including context 'ext-meetme' in context 'custom-meetme' Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 's' priority 1 to custom-meetme Sep 4 13:06:02 DEBUG[30937] pbx.c: Added extension 'h' priority 1 to custom-meetme Sep 4 13:06:02 WARNING[30937] pbx.c: Context 'from-internal' tries includes nonexistent context 'app-displayvar' Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_spool.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_spool.so] => (Outgoing Spool Support) Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_dundi.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_dundi.so] => (Distributed Universal Number Discovery (DUNDi)) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/dundi.conf Sep 4 13:06:02 DEBUG[30937] pbx_dundi.c: Seeding global EID '00:11:d8:d4:b2:b6' from 'eth0' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Using TOS bits 0 Sep 4 13:06:02 VERBOSE[30937] logger.c: == DUNDi Ready and Listening on 0.0.0.0 port 4520 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DUNDiLookup' Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_loopback.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_loopback.so] => (Loopback Switch) Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_realtime.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_realtime.so] => (Realtime Switch) Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_ael.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_ael.so] => (Asterisk Extension Language Compiler) Sep 4 13:06:02 NOTICE[30937] pbx_ael.c: Unknown root token '' Sep 4 13:06:02 WARNING[30937] pbx.c: Requested contexts didn't get merged Sep 4 13:06:02 WARNING[30937] pbx.c: Context 'from-internal' tries includes nonexistent context 'app-displayvar' Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_functions.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [pbx_functions.so] => (Builtin dialplan functions) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function MD5 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function CHECK_MD5 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function MATH Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function GROUP_COUNT Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function GROUP_MATCH_COUNT Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function GROUP Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function GROUP_LIST Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function FIELDQTY Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function REGEX Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function LEN Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function STRFTIME Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function EVAL Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function CDR Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function ISNULL Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function SET Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function EXISTS Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function IF Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function IFTIME Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function ENV Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function DB Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function DB_EXISTS Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function TIMEOUT Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function LANGUAGE Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function MUSICCLASS Sep 4 13:06:02 VERBOSE[30937] logger.c: [skipping chan_oss.so] Sep 4 13:06:02 VERBOSE[30937] logger.c: [skipping chan_phone.so] Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dial.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dial.so] => (Dialing Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Dial' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'RetryDial' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_playback.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_playback.so] => (Sound File Playback Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Playback' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_voicemail.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_voicemail.so] => (Comedian Mail (Voicemail System)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'VoiceMail' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'VoiceMailMain' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MailboxExists' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'VMAuthenticate' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='voicemail.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 13 rows. Sep 4 13:06:02 DEBUG[30937] app_voicemail.c: VM CID Info before msg disabled globally Sep 4 13:06:02 DEBUG[30937] app_voicemail.c: Send Voicemail msg disabled globally Sep 4 13:06:02 DEBUG[30937] app_voicemail.c: ENVELOPE before msg enabled globally Sep 4 13:06:02 DEBUG[30937] app_voicemail.c: Duration info before msg enabled globally Sep 4 13:06:02 DEBUG[30937] app_voicemail.c: We are not going to skip to the next msg after save/delete Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_directory.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_directory.so] => (Extension Directory) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Directory' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_mp3.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_mp3.so] => (Silly MP3 Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MP3Player' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_system.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_system.so] => (Generic System() application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'TrySystem' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'System' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_echo.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_echo.so] => (Simple Echo Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Echo' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_record.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_record.so] => (Trivial Record Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Record' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_image.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_image.so] => (Image Transmission Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SendImage' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_url.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_url.so] => (Send URL Applications) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SendURL' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_disa.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_disa.so] => (DISA (Direct Inward System Access) Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DISA' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_adsiprog.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_adsiprog.so] => (Asterisk ADSI Programming Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ADSIProg' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_getcpeid.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_getcpeid.so] => (Get ADSI CPE ID) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'GetCPEID' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_milliwatt.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_milliwatt.so] => (Digital Milliwatt (mu-law) Test Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Milliwatt' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapateller.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapateller.so] => (Block Telemarketers with Special Information Tone) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Zapateller' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcallerid.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcallerid.so] => (Set CallerID Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetCallerPres' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetCallerID' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_festival.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_festival.so] => (Simple Festival Interface) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Festival' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_queue.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_queue.so] => (True Call Queueing) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Queue' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action Queues Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action QueueStatus Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action QueueAdd Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action QueueRemove Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action QueuePause Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AddQueueMember' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'RemoveQueueMember' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'PauseQueueMember' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'UnpauseQueueMember' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function QUEUEAGENTCOUNT Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/queues.conf Sep 4 13:06:02 NOTICE[30937] app_queue.c: No call queueing config file (queues.conf), so no call queues Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_senddtmf.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_senddtmf.so] => (Send DTMF digits Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SendDTMF' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_parkandannounce.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_parkandannounce.so] => (Call Parking and Announce Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ParkAndAnnounce' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_striplsd.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_striplsd.so] => (Strip trailing digits) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'StripLSD' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcidname.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcidname.so] => (Set CallerID Name) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetCIDName' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_lookupcidname.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_lookupcidname.so] => (Look up CallerID Name from local database) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'LookupCIDName' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_substring.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_substring.so] => ((Deprecated) Save substring digits in a given variable) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SubString' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_macro.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_macro.so] => (Extension Macros) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MacroExit' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MacroIf' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Macro' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_authenticate.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_authenticate.so] => (Authentication Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Authenticate' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_softhangup.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_softhangup.so] => (Hangs up the requested channel) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SoftHangup' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_lookupblacklist.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_lookupblacklist.so] => (Look up Caller*ID name/number from blacklist database) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'LookupBlacklist' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_waitforring.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_waitforring.so] => (Waits until first ring after time) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'WaitForRing' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_privacy.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_privacy.so] => (Require phone number to be entered, if no CallerID sent) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'PrivacyManager' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_db.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_db.so] => (Database access functions for Asterisk extension logic) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DBget' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DBput' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DBdel' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DBdeltree' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_chanisavail.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_chanisavail.so] => (Check if channel is available) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ChanIsAvail' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_enumlookup.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_enumlookup.so] => (ENUM Lookup) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'EnumLookup' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='enum.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 2 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_transfer.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_transfer.so] => (Transfer) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Transfer' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcidnum.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcidnum.so] => (Set CallerID Number) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetCIDNum' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_cdr.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_cdr.so] => (Make sure asterisk doesn't save CDR for a certain call) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'NoCDR' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_hasnewvoicemail.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_hasnewvoicemail.so] => (Indicator for whether a voice mailbox has messages in a given folder.[) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'HasVoicemail' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'HasNewVoicemail' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sayunixtime.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sayunixtime.so] => (Say time) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SayUnixTime' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DateTime' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_cut.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_cut.so] => (String manipulation) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function CUT Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function SORT Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Sort' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Cut' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_read.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_read.so] => (Read Variable Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Read' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcdruserfield.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setcdruserfield.so] => (CDR user field apps) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetCDRUserField' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AppendCDRUserField' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action SetCDRUserField Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_random.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_random.so] => (Random goto) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Random' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_ices.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_ices.so] => (Encode and Stream via icecast and ices) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ICES' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_eval.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_eval.so] => (Reevaluates strings) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Eval' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_nbscat.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_nbscat.so] => (Silly NBS Stream Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'NBScat' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sendtext.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sendtext.so] => (Send Text Applications) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SendText' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_exec.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_exec.so] => (Executes applications) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Exec' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sms.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_sms.so] => (SMS/PSTN handler) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SMS' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_groupcount.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_groupcount.so] => (Group Management Routines) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'GetGroupCount' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetGroup' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'CheckGroup' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'GetGroupMatchCount' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_txtcidname.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_txtcidname.so] => (TXTCIDName) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'TXTCIDName' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='enum.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 2 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_controlplayback.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_controlplayback.so] => (Control Playback Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ControlPlayback' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_talkdetect.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_talkdetect.so] => (Playback with Talk Detection) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'BackgroundDetect' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_alarmreceiver.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_alarmreceiver.so] => (Alarm Receiver for Asterisk) Sep 4 13:06:02 DEBUG[30937] config.c: No file to parse: /etc/asterisk/alarmreceiver.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: > AlarmReceiver: No config file Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'AlarmReceiver' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_userevent.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_userevent.so] => (Custom User Event Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'UserEvent' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_verbose.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_verbose.so] => (Send verbose output) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Verbose' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_test.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_test.so] => (Interface Test Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'TestClient' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'TestServer' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_forkcdr.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_forkcdr.so] => (Fork The CDR into 2 separate entities.) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ForkCDR' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_math.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_math.so] => (Basic Math Functions) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Math' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dumpchan.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dumpchan.so] => (Dump Info About The Calling Channel) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'DumpChan' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_waitforsilence.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_waitforsilence.so] => (Wait For Silence) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'WaitForSilence' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_while.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_while.so] => (While Loops and Conditional Execution) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'While' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ExecIf' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'EndWhile' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setrdnis.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_setrdnis.so] => (Set RDNIS Number) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetRDNIS' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_md5.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_md5.so] => (MD5 checksum applications) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MD5Check' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MD5' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_readfile.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_readfile.so] => (Stores output of file into a variable) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ReadFile' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_chanspy.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_chanspy.so] => (Tap into any type of asterisk channel and listen to audio) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ChanSpy' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_settransfercapability.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_settransfercapability.so] => (Set ISDN Transfer Capability) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SetTransferCapability' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dictate.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_dictate.so] => (Virtual Dictation Machine) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Dictate' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapras.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapras.so] => (Zap RAS Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ZapRAS' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_meetme.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_meetme.so] => (MeetMe conference bridge) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MeetMeAdmin' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MeetMeCount' Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MeetMe' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_flash.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_flash.so] => (Flash zap trunk application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'Flash' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapbarge.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapbarge.so] => (Barge in on Zap channel application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ZapBarge' Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapscan.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_zapscan.so] => (Scan Zap channels application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ZapScan' Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_ilbc.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_ilbc.so] => (iLBC/PCM16 (signed linear) Codec Translator) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'ilbctolin' from format ilbc to slin, cost 3 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintoilbc' from format slin to ilbc, cost 15 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_gsm.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_gsm.so] => (GSM/PCM16 (signed linear) Codec Translator) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_gsm: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'gsmtolin' from format gsm to slin, cost 4 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintogsm' from format slin to gsm, cost 7 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_lpc10.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_lpc10.so] => (LPC10 2.4kbps (signed linear) Voice Coder) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_lpc10: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lpc10tolin' from format lpc10 to slin, cost 4 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintolpc10' from format slin to lpc10, cost 8 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_adpcm.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_adpcm.so] => (Adaptive Differential PCM Coder/Decoder) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_adpcm: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'adpcmtolin' from format adpcm to slin, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintoadpcm' from format slin to adpcm, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_ulaw.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_ulaw.so] => (Mu-law Coder/Decoder) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_ulaw: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'ulawtolin' from format ulaw to slin, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintoulaw' from format slin to ulaw, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_alaw.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_alaw.so] => (A-law Coder/Decoder) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_alaw: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'alawtolin' from format alaw to slin, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintoalaw' from format slin to alaw, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_a_mu.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_a_mu.so] => (A-law and Mulaw direct Coder/Decoder) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'alawtoulaw' from format alaw to ulaw, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'ulawtoalaw' from format ulaw to alaw, cost 1 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_g726.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_g726.so] => (ITU G.726-32kbps G726 Transcoder) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='codecs.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 9 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: -- codec_g726: using generic PLC Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'g726tolin' from format g726 to slin, cost 10 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from ulaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 18 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 25 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintog726' from format slin to g726, cost 11 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from g723 to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from gsm to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 13 cost path from unknown to ulaw, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from ulaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 18 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 25 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 14 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_gsm.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_gsm.so] => (Raw GSM data) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format gsm, extension(s) gsm Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_wav.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_wav.so] => (Microsoft WAV format (8000hz Signed Linear)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format wav, extension(s) wav Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_wav_gsm.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_wav_gsm.so] => (Microsoft WAV format (Proprietary GSM)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format wav49, extension(s) WAV|wav49 Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_vox.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_vox.so] => (Dialogic VOX (ADPCM) File Format) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format vox, extension(s) vox Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_pcm.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_pcm.so] => (Raw uLaw 8khz Audio support (PCM)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format pcm, extension(s) pcm|ulaw|ul|mu Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g729.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g729.so] => (Raw G729 data) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g729, extension(s) g729 Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_pcm_alaw.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_pcm_alaw.so] => (Raw aLaw 8khz PCM Audio support) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format alaw, extension(s) alaw|al Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_h263.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_h263.so] => (Raw h263 data) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format h263, extension(s) h263 Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g726.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g726.so] => (Raw G.726 (16/24/32/40kbps) data) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g726-40, extension(s) g726-40 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g726-32, extension(s) g726-32 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g726-24, extension(s) g726-24 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g726-16, extension(s) g726-16 Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_ilbc.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_ilbc.so] => (Raw iLBC data) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format iLBC, extension(s) ilbc Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_sln.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_sln.so] => (Raw Signed Linear Audio support (SLN)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format sln, extension(s) sln|raw Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_au.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_au.so] => (Sun Microsystems AU format (signed linear)) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format au, extension(s) au Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_jpeg.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_jpeg.so] => (JPEG (Joint Picture Experts Group) Image Format) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered format 'jpg' (JPEG (Joint Picture Experts Group)) Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g723.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_g723.so] => (G.723.1 Simple Timestamp File Format) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format g723sf, extension(s) g723|g723sf Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_csv.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_csv.so] => (Comma Separated Values CDR Backend) Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_manager.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_manager.so] => (Asterisk Call Manager CDR Backend) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='cdr_manager.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 1 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_custom.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_custom.so] => (Customizable Comma Separated Values CDR Backend) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='cdr_custom.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 1 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_odbc.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_odbc.so] => (ODBC CDR Backend) Sep 4 13:06:02 DEBUG[30937] config.c: Parsing /etc/asterisk/cdr_odbc.conf Sep 4 13:06:02 VERBOSE[30937] logger.c: [func_callerid.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [func_callerid.so] => (Caller ID related dialplan function) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function CALLERID Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_mp3.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [format_mp3.so] => (MP3 format [Any rate but 8000hz mono optimal]) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered file format mp3, extension(s) mp3 Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_saycountpl.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_saycountpl.so] => (Say polish counting words) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'SayCountPL' Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_addon_mysql.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [cdr_addon_mysql.so] => (MySQL CDR Backend) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='cdr_mysql.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 6 rows. Sep 4 13:06:02 NOTICE[30937] cdr_addon_mysql.c: MySQL database table not specified. Assuming "cdr" Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got hostname of localhost Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got port of 3306 Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got sock file of /var/lib/mysql/mysql.sock Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got user of asterisk Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got dbname of asterisk_realtime Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: got password of asterisk Sep 4 13:06:02 DEBUG[30937] cdr_addon_mysql.c: Successfully connected to MySQL database. Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_addon_sql_mysql.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_addon_sql_mysql.so] => (Simple Mysql Interface) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'MYSQL' Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_g729.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [codec_g729.so] => (G729/PCM16 (signed linear) Codec Translator, based on IPP) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'g729tolin' from format g729 to slin, cost 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from g723 to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from gsm to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 13 cost path from unknown to ulaw, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from ulaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 18 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 25 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from alaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 13 cost path from alaw to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 14 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered translator 'lintog729' from format slin to g729, cost 8 Sep 4 13:06:02 DEBUG[30937] translate.c: Reseting translation matrix Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from g723 to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from g723 to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from g723 to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from gsm to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from gsm to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from gsm to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from gsm to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to g723, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 13 cost path from unknown to ulaw, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to alaw, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from unknown to unknown, via 2 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from ulaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 18 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 18 cost path from ulaw to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 25 cost path from ulaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 8 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 2 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from unknown to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 16 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 15 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 5 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 12 cost path from unknown to alaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 19 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 9 cost path from alaw to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 13 cost path from alaw to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 3 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 17 cost path from alaw to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 10 cost path from unknown to g723, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to gsm, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 14 cost path from unknown to ulaw, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 4 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to unknown, via 6 Sep 4 13:06:02 DEBUG[30937] translate.c: Discovered 11 cost path from unknown to alaw, via 6 Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_externalivr.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [app_externalivr.so] => (External IVR Interface Application) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered application 'ExternalIVR' Sep 4 13:06:02 VERBOSE[30937] logger.c: [func_uri.so]Sep 4 13:06:02 VERBOSE[30937] logger.c: [func_uri.so] => (URI encode/decode functions) Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function URIDECODE Sep 4 13:06:02 VERBOSE[30937] logger.c: == Registered custom function URIENCODE Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action DBGet Sep 4 13:06:02 VERBOSE[30937] logger.c: == Manager registered action DBPut Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM ast_config WHERE filename='enum.conf' and commented=0 ORDER BY filename, cat_metric desc, var_metric asc, category, var_name, var_val, id Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Found 2 rows. Sep 4 13:06:02 VERBOSE[30937] logger.c: Asterisk Ready. Sep 4 13:06:02 VERBOSE[30937] logger.c: Asterisk Ready. Asterisk Malloc Debugger Started (see /var/log/asterisk/mmlog)) Sep 4 13:06:02 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 583cf75c28afe03f4e2f2c402ac5149d@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:02 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:02 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:02 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #15 Sep 4 13:06:02 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 17a7c12f11946a8a7ecc7b095aa03eb7@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:02 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #17 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 7cfec43526f886333056c81c6d8932b6@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #19 Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 10.1.1.41 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 613b3a10-afe8ceda-3e99ae2b@10.1.1.41 - REGISTER (No RTP) Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '256' Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:03 VERBOSE[30937] logger.c: -- SIP Seeding peer from astdb: '256' at 256@10.1.1.41:5060 for 3600 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 10.1.1.41 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 10.1.1.41 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '583cf75c28afe03f4e2f2c402ac5149d@10.1.1.12' of Request 102: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '17a7c12f11946a8a7ecc7b095aa03eb7@10.1.1.12' of Request 102: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 799437ae691f48b726149f201755b0c9@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:03 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #30 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '7cfec43526f886333056c81c6d8932b6@10.1.1.12' of Request 102: Match Found Sep 4 13:06:03 VERBOSE[30937] logger.c: -- Saved useragent "PolycomSoundPointIP-SPIP_500-UA/1.5.2.0054" for peer 256 Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE users SET ipaddr = '10.1.1.41', port = '5060', regseconds = '1125867963', username = '256' WHERE name = '256' Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:03 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: users Sep 4 13:06:03 DEBUG[30937] app_queue.c: Device 'SIP/256' changed to state '1' (Not in use) Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '583cf75c28afe03f4e2f2c402ac5149d@10.1.1.12' of Request 103: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Cancelling timeout 15 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '4093c19e57d04aeb48116e2c0505e614@10.1.1.12' of Request 102: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '17a7c12f11946a8a7ecc7b095aa03eb7@10.1.1.12' of Request 103: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Cancelling timeout 17 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '799437ae691f48b726149f201755b0c9@10.1.1.12' of Request 102: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '7cfec43526f886333056c81c6d8932b6@10.1.1.12' of Request 103: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Cancelling timeout 19 Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Stopping retransmission on '799437ae691f48b726149f201755b0c9@10.1.1.12' of Request 103: Match Found Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:03 DEBUG[30937] chan_sip.c: Cancelling timeout 30 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Stopping retransmission on '2c669d760900c5e032a6193631471956@10.1.1.12' of Request 102: Match Found Sep 4 13:06:04 DEBUG[30937] app_queue.c: Device 'SIP/256' changed to state '1' (Not in use) Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 3e72f0130ca21c53376730f02a97424c@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:04 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #45 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 1875a37e17421e8771e25d3f2b182940@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:04 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #47 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 4c44de9e7c9ba6ba6a24d9714b812eb2@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:04 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #49 Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Stopping retransmission on '3e72f0130ca21c53376730f02a97424c@10.1.1.12' of Request 102: Match Found Sep 4 13:06:04 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 07895e1439f964375a3e8e1b7a5f01fe@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:04 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:05 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #52 Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '1875a37e17421e8771e25d3f2b182940@10.1.1.12' of Request 102: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '4c44de9e7c9ba6ba6a24d9714b812eb2@10.1.1.12' of Request 102: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '3e72f0130ca21c53376730f02a97424c@10.1.1.12' of Request 103: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Cancelling timeout 45 Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '07895e1439f964375a3e8e1b7a5f01fe@10.1.1.12' of Request 102: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '1875a37e17421e8771e25d3f2b182940@10.1.1.12' of Request 103: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Cancelling timeout 47 Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '4c44de9e7c9ba6ba6a24d9714b812eb2@10.1.1.12' of Request 103: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Cancelling timeout 49 Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Stopping retransmission on '07895e1439f964375a3e8e1b7a5f01fe@10.1.1.12' of Request 103: Match Found Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:05 DEBUG[30937] chan_sip.c: Cancelling timeout 52 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 3ee333db16b37ec75ec4ecf363cad308@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:06 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #65 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 51c2a12d4d4a886e4d6645bc46fa7d6a@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:06 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #67 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '3ee333db16b37ec75ec4ecf363cad308@10.1.1.12' of Request 102: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 5b796a1a190370b769d18add5ca95ff7@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:06 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #70 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '51c2a12d4d4a886e4d6645bc46fa7d6a@10.1.1.12' of Request 102: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 3996360d28cfadd12fb0e6c448268a42@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:06 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:06 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #73 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '3ee333db16b37ec75ec4ecf363cad308@10.1.1.12' of Request 103: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Cancelling timeout 65 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '5b796a1a190370b769d18add5ca95ff7@10.1.1.12' of Request 102: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '51c2a12d4d4a886e4d6645bc46fa7d6a@10.1.1.12' of Request 103: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Cancelling timeout 67 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '3996360d28cfadd12fb0e6c448268a42@10.1.1.12' of Request 102: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '5b796a1a190370b769d18add5ca95ff7@10.1.1.12' of Request 103: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Cancelling timeout 70 Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Stopping retransmission on '3996360d28cfadd12fb0e6c448268a42@10.1.1.12' of Request 103: Match Found Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:06 DEBUG[30937] chan_sip.c: Cancelling timeout 73 Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 488a60480c495d3d01e754d01caee5c7@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:07 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:07 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:07 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #85 Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Stopping retransmission on '488a60480c495d3d01e754d01caee5c7@10.1.1.12' of Request 102: Match Found Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Stopping retransmission on '488a60480c495d3d01e754d01caee5c7@10.1.1.12' of Request 103: Match Found Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Cancelling timeout 85 Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 03f640b22abbe0275a82457d3ceec409@10.1.1.12 - REGISTER (No RTP) Sep 4 13:06:07 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35' Sep 4 13:06:07 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:07 DEBUG[30937] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0 Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip Sep 4 13:06:07 DEBUG[30937] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id #90 Sep 4 13:06:08 VERBOSE[30937] logger.c: Retransmitting #1 (no NAT) to 66.165.175.35:5060: REGISTER sip:66.165.175.35 SIP/2.0 Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK20ce5f23 From: ;tag=as6eb3afb1 To: Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 102 REGISTER User-Agent: Asterisk PBX Expires: 120 Contact: Event: registration Content-Length: 0 --- Sep 4 13:06:08 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK20ce5f23 From: ;tag=as6eb3afb1 To: ;tag=4042143d22c03347e15cee7ccb51d867-a731 Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 102 REGISTER WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b54dcbe3342ed5c880af0b63892034da3c808" Server: Sip EXpress router (0.9.0 (i386/freebsd)) Content-Length: 0 Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (9 headers 0 lines)Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (9 headers 0 lines)--- Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Stopping retransmission on '03f640b22abbe0275a82457d3ceec409@10.1.1.12' of Request 102: Match Found Sep 4 13:06:08 VERBOSE[30937] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35 Sep 4 13:06:08 VERBOSE[30937] logger.c: REGISTER 12 headers, 0 lines Sep 4 13:06:08 VERBOSE[30937] logger.c: Reliably Transmitting (no NAT) to 66.165.175.35:5060: REGISTER sip:66.165.175.35 SIP/2.0 Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK3fdfb883 From: ;tag=as3611de59 To: Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 103 REGISTER User-Agent: Asterisk PBX Authorization: Digest username="19712448062", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b54dcbe3342ed5c880af0b63892034da3c808", response="0010e768f3170f281c6db1102eaa2ce0", opaque="" Expires: 120 Contact: Event: registration Content-Length: 0 --- Sep 4 13:06:08 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK20ce5f23 From: ;tag=as6eb3afb1 To: ;tag=4042143d22c03347e15cee7ccb51d867-a731 Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 102 REGISTER WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b54dcbe3342ed5c880af0b63892034da3c808" Server: Sip EXpress router (0.9.0 (i386/freebsd)) Content-Length: 0 Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (9 headers 0 lines)Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (9 headers 0 lines)--- Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Stopping retransmission on '03f640b22abbe0275a82457d3ceec409@10.1.1.12' of Request 102: Match Not Found Sep 4 13:06:08 VERBOSE[30937] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35 Sep 4 13:06:08 VERBOSE[30937] logger.c: REGISTER 12 headers, 0 lines Sep 4 13:06:08 VERBOSE[30937] logger.c: Reliably Transmitting (no NAT) to 66.165.175.35:5060: REGISTER sip:66.165.175.35 SIP/2.0 Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK5d66f7f8 From: ;tag=as6469dd3d To: Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 104 REGISTER User-Agent: Asterisk PBX Authorization: Digest username="19712448062", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b54dcbe3342ed5c880af0b63892034da3c808", response="0010e768f3170f281c6db1102eaa2ce0", opaque="" Expires: 120 Contact: Event: registration Content-Length: 0 --- Sep 4 13:06:08 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK3fdfb883 From: ;tag=as3611de59 To: ;tag=4042143d22c03347e15cee7ccb51d867-5655 Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 103 REGISTER PortaBilling: available-funds:100.00 currency:USD Contact: ;expires=115 Server: Sip EXpress router (0.9.0 (i386/freebsd)) Content-Length: 0 Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Stopping retransmission on '03f640b22abbe0275a82457d3ceec409@10.1.1.12' of Request 103: Match Found Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Registration successful Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Cancelling timeout 90 Sep 4 13:06:08 VERBOSE[30937] logger.c: Scheduling destruction of call '03f640b22abbe0275a82457d3ceec409@10.1.1.12' in 32000 ms Sep 4 13:06:08 NOTICE[30937] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s) Sep 4 13:06:08 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK5d66f7f8 From: ;tag=as6469dd3d To: ;tag=4042143d22c03347e15cee7ccb51d867-52d8 Call-ID: 03f640b22abbe0275a82457d3ceec409@10.1.1.12 CSeq: 104 REGISTER PortaBilling: available-funds:100.00 currency:USD Contact: ;expires=115 Server: Sip EXpress router (0.9.0 (i386/freebsd)) Content-Length: 0 Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:08 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:08 DEBUG[30937] chan_sip.c: Stopping retransmission on '03f640b22abbe0275a82457d3ceec409@10.1.1.12' of Request 104: Match Found Sep 4 13:06:08 WARNING[30937] chan_sip.c: Got 200 OK on REGISTER that isn't a register Sep 4 13:06:08 VERBOSE[30937] logger.c: Destroying call '03f640b22abbe0275a82457d3ceec409@10.1.1.12' Sep 4 13:06:09 VERBOSE[30937] logger.c: <-- SIP read from 67.189.14.105:5060: INVITE sip:*98@67.189.77.121:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bK1ef8c1406B87CCA3 From: "354" ;tag=A24512A-CBC1B2BF To: CSeq: 1 INVITE Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1 Supported: 100rel,replace Allow-Events: talk,hold,conference Max-Forwards: 70 Content-Type: application/sdp Content-Length: 239 v=0 o=- 1119300496 1119300496 IN IP4 192.168.1.102 s=Polycom IP Phone c=IN IP4 192.168.1.102 t=0 0 m=audio 2264 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (14 headers 10 lines)Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (14 headers 10 lines)--- Sep 4 13:06:09 DEBUG[30937] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0 Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 - INVITE (With RTP) Sep 4 13:06:09 DEBUG[30937] chan_sip.c: * SIP extension value: 2 for call 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 Sep 4 13:06:09 VERBOSE[30937] logger.c: Using INVITE request as basis request - 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 Sep 4 13:06:09 VERBOSE[30937] logger.c: Sending to 192.168.1.102 : 5060 (non-NAT) Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '354' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE ipaddr = '67.189.14.105' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 VERBOSE[30937] logger.c: -- SIP Seeding peer from astdb: '354' at 354@67.189.14.105:5060 for 3600 Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Sep 4 13:06:09 DEBUG[30937] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0 Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip Sep 4 13:06:09 VERBOSE[30937] logger.c: 11 headers, 0 lines Sep 4 13:06:09 VERBOSE[30937] logger.c: Reliably Transmitting (no NAT) to 67.189.14.105:5060: OPTIONS sip:354@192.168.1.102 SIP/2.0 Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK77c7373a From: "asterisk" ;tag=as0bdde25a To: Contact: Call-ID: 6f65953e4593fa803318108b163b760e@67.189.77.121 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Date: Sun, 04 Sep 2005 20:06:09 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Sep 4 13:06:09 VERBOSE[30937] logger.c: Found peer '354' Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Setting NAT on RTP to 524288 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found RTP audio format 0 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found RTP audio format 8 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found RTP audio format 18 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found RTP audio format 101 Sep 4 13:06:09 VERBOSE[30937] logger.c: Peer audio RTP is at port 192.168.1.102:2264 Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Peer audio RTP is at port 192.168.1.102:2264 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found description format PCMU Sep 4 13:06:09 VERBOSE[30937] logger.c: Found description format PCMA Sep 4 13:06:09 VERBOSE[30937] logger.c: Found description format G729 Sep 4 13:06:09 VERBOSE[30937] logger.c: Found description format telephone-event Sep 4 13:06:09 VERBOSE[30937] logger.c: Capabilities: us - 0x10e (gsm|ulaw|alaw|g729), peer - audio=0x10c (ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x10c (ulaw|alaw|g729) Sep 4 13:06:09 VERBOSE[30937] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Checking SIP call limits for device 354 Sep 4 13:06:09 VERBOSE[30937] logger.c: Looking for *98 in from-internal Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] chan_sip.c: build_route: Contact hop: Sep 4 13:06:09 VERBOSE[30937] logger.c: list_route: hop: Sep 4 13:06:09 VERBOSE[30937] logger.c: Transmitting (NAT) to 67.189.14.105:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bK1ef8c1406B87CCA3;received=67.189.14.105;rport=5060 From: "354" ;tag=A24512A-CBC1B2BF To: Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 VERBOSE[30937] logger.c: -- Executing Answer("SIP/354-a2cd", "") Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 4 13:06:09 DEBUG[30937] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0 Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip Sep 4 13:06:09 VERBOSE[30937] logger.c: 11 headers, 3 lines Sep 4 13:06:09 VERBOSE[30937] logger.c: Reliably Transmitting (NAT) to 67.189.14.105:5060: NOTIFY sip:354@192.168.1.102 SIP/2.0 Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK3e381385;rport From: "asterisk" ;tag=as28099e62 To: Contact: Call-ID: 38420c0d6ab0d1a847b6d98f014d0f9c@67.189.77.121 CSeq: 102 NOTIFY User-Agent: Asterisk PBX Event: message-summary Content-Type: application/simple-message-summary Content-Length: 80 Message-Account: sip:asterisk@ Messages-Waiting: no Voice-Message: 0/1 (0/0) --- Sep 4 13:06:09 VERBOSE[30937] logger.c: Scheduling destruction of call '38420c0d6ab0d1a847b6d98f014d0f9c@67.189.77.121' in 15000 ms Sep 4 13:06:09 DEBUG[30937] app_queue.c: Device 'SIP/354' changed to state '2' (In use) Sep 4 13:06:09 DEBUG[30937] channel.c: Avoiding initial deadlock for 'SIP/354-a2cd' Sep 4 13:06:09 DEBUG[30937] chan_sip.c: sip_answer(SIP/354-a2cd) Sep 4 13:06:09 VERBOSE[30937] logger.c: We're at 67.189.77.121 port 11984 Sep 4 13:06:09 VERBOSE[30937] logger.c: Answering with preferred capability 0x4 (ulaw) Sep 4 13:06:09 VERBOSE[30937] logger.c: Answering with preferred capability 0x8 (alaw) Sep 4 13:06:09 VERBOSE[30937] logger.c: Answering with preferred capability 0x100 (g729) Sep 4 13:06:09 VERBOSE[30937] logger.c: Answering with preferred capability 0x2 (gsm) Sep 4 13:06:09 VERBOSE[30937] logger.c: Answering with non-codec capability 0x1 (telephone-event) Sep 4 13:06:09 VERBOSE[30937] logger.c: Reliably Transmitting (NAT) to 67.189.14.105:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bK1ef8c1406B87CCA3;received=67.189.14.105;rport=5060 From: "354" ;tag=A24512A-CBC1B2BF To: ;tag=as49bb38b3 Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 291 v=0 o=root 30937 30937 IN IP4 67.189.77.121 s=session c=IN IP4 67.189.77.121 t=0 0 m=audio 11984 RTP/AVP 0 8 18 3 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '2' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '2' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '2' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '2' ORDER BY exten Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2' Sep 4 13:06:09 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:09 VERBOSE[30937] logger.c: -- Executing Wait("SIP/354-a2cd", "1") Sep 4 13:06:09 DEBUG[30937] app_queue.c: Device 'SIP/354' changed to state '2' (In use) Sep 4 13:06:09 VERBOSE[30937] logger.c: <-- SIP read from 67.189.14.105:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK77c7373a From: "asterisk" ;tag=as0bdde25a To: ;tag=DE0CCF5B-746E3FB8 CSeq: 102 OPTIONS Call-ID: 6f65953e4593fa803318108b163b760e@67.189.77.121 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1 Content-Length: 0 Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Stopping retransmission on '6f65953e4593fa803318108b163b760e@67.189.77.121' of Request 102: Match Found Sep 4 13:06:09 VERBOSE[30937] logger.c: Destroying call '6f65953e4593fa803318108b163b760e@67.189.77.121' Sep 4 13:06:09 DEBUG[30937] app_queue.c: Device 'SIP/354' changed to state '2' (In use) Sep 4 13:06:09 VERBOSE[30937] logger.c: <-- SIP read from 67.189.14.105:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 67.189.77.121:5060;branch=z9hG4bK3e381385;rport From: "asterisk" ;tag=as28099e62 To: ;tag=7F7BFEB4-A8237919 CSeq: 102 NOTIFY Call-ID: 38420c0d6ab0d1a847b6d98f014d0f9c@67.189.77.121 Contact: Event: message-summary User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1 Content-Length: 0 Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Stopping retransmission on '38420c0d6ab0d1a847b6d98f014d0f9c@67.189.77.121' of Request 102: Match Found Sep 4 13:06:09 VERBOSE[30937] logger.c: Destroying call '38420c0d6ab0d1a847b6d98f014d0f9c@67.189.77.121' Sep 4 13:06:09 VERBOSE[30937] logger.c: <-- SIP read from 67.189.14.105:5060: ACK sip:*98@67.189.77.121 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bKce721277CEE66B22 From: "354" ;tag=A24512A-CBC1B2BF To: ;tag=as49bb38b3 CSeq: 1 ACK Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1 Max-Forwards: 70 Content-Length: 0 Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (11 headers 0 lines)Sep 4 13:06:09 VERBOSE[30937] logger.c: --- (11 headers 0 lines)--- Sep 4 13:06:09 DEBUG[30937] chan_sip.c: Stopping retransmission on '9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102' of Response 1: Match Found Sep 4 13:06:09 DEBUG[30937] rtp.c: RTP NAT: Using address 67.189.14.105:2264 Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14565, ts 638080451, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14566, ts 638080611, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14567, ts 638080771, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14568, ts 638080931, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14569, ts 638081091, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14570, ts 638081251, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14571, ts 638081411, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14572, ts 638081571, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14573, ts 638081731, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14574, ts 638081891, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14575, ts 638082051, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14576, ts 638082211, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14577, ts 638082371, len 160) Sep 4 13:06:09 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14578, ts 638082531, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14579, ts 638082691, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14580, ts 638082851, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14581, ts 638083011, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14582, ts 638083171, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14583, ts 638083331, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14584, ts 638083491, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14585, ts 638083651, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14586, ts 638083811, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14587, ts 638083971, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14588, ts 638084131, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14589, ts 638084291, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14590, ts 638084451, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14591, ts 638084611, len 160) Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '3' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '3' ORDER BY exten Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'from-internal-rt' AND priority = '3' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '3' ORDER BY exten Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 VERBOSE[30937] logger.c: -- Executing VoiceMailMain("SIP/354-a2cd", "default") Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM voicemail WHERE mailbox = 'default' AND context = 'default' Sep 4 13:06:10 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:10 DEBUG[30937] channel.c: Set channel SIP/354-a2cd to write format gsm Sep 4 13:06:10 DEBUG[30937] rtp.c: Ooh, format changed from unknown to ulaw Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49689, ts 160, len 160) Sep 4 13:06:10 DEBUG[30937] channel.c: Scheduling timer at 160 sample intervals Sep 4 13:06:10 VERBOSE[30937] logger.c: -- Playing 'vm-login' (language 'en') Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14592, ts 638084771, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14593, ts 638084931, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14594, ts 638085091, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49690, ts 320, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49691, ts 480, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14595, ts 638085251, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49692, ts 640, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14596, ts 638085411, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49693, ts 800, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14597, ts 638085571, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49694, ts 960, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14598, ts 638085731, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49695, ts 1120, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14599, ts 638085891, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49696, ts 1280, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14600, ts 638086051, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49697, ts 1440, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14601, ts 638086211, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49698, ts 1600, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14602, ts 638086371, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49699, ts 1760, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14603, ts 638086531, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49700, ts 1920, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14604, ts 638086691, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49701, ts 2080, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14605, ts 638086851, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49702, ts 2240, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14606, ts 638087011, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49703, ts 2400, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14607, ts 638087171, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49704, ts 2560, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14608, ts 638087331, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49705, ts 2720, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14609, ts 638087491, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49706, ts 2880, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14610, ts 638087651, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49707, ts 3040, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14611, ts 638087811, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49708, ts 3200, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14612, ts 638087971, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49709, ts 3360, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14613, ts 638088131, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49710, ts 3520, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14614, ts 638088291, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49711, ts 3680, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14615, ts 638088451, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49712, ts 3840, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14616, ts 638088611, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49713, ts 4000, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14617, ts 638088771, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49714, ts 4160, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14618, ts 638088931, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49715, ts 4320, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14619, ts 638089091, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49716, ts 4480, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14620, ts 638089251, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49717, ts 4640, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14621, ts 638089411, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49718, ts 4800, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14622, ts 638089571, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49719, ts 4960, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14623, ts 638089731, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49720, ts 5120, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14624, ts 638089891, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49721, ts 5280, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14625, ts 638090051, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49722, ts 5440, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14626, ts 638090211, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49723, ts 5600, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14627, ts 638090371, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49724, ts 5760, len 160) Sep 4 13:06:10 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14628, ts 638090531, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49725, ts 5920, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14629, ts 638090691, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49726, ts 6080, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14630, ts 638090851, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49727, ts 6240, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14631, ts 638091011, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49728, ts 6400, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14632, ts 638091171, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49729, ts 6560, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14633, ts 638091331, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49730, ts 6720, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14634, ts 638091491, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49731, ts 6880, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14635, ts 638091651, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49732, ts 7040, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14636, ts 638091811, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49733, ts 7200, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14637, ts 638091971, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49734, ts 7360, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14638, ts 638092131, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49735, ts 7520, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14639, ts 638092291, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49736, ts 7680, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14640, ts 638092451, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49737, ts 7840, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14641, ts 638092611, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49738, ts 8000, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14642, ts 638092771, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49739, ts 8160, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14643, ts 638092931, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49740, ts 8320, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14644, ts 638093091, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14645, ts 638093251, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49741, ts 8480, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49742, ts 8640, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14646, ts 638093411, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49743, ts 8800, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14647, ts 638093571, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49744, ts 8960, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14648, ts 638093731, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49745, ts 9120, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14649, ts 638093891, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49746, ts 9280, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14650, ts 638094051, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49747, ts 9440, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14651, ts 638094211, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49748, ts 9600, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14652, ts 638094371, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49749, ts 9760, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14653, ts 638094531, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49750, ts 9920, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14654, ts 638094691, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49751, ts 10080, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14655, ts 638094851, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49752, ts 10240, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14656, ts 638095011, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49753, ts 10400, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14657, ts 638095171, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49754, ts 10560, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14658, ts 638095331, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49755, ts 10720, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14659, ts 638095491, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49756, ts 10880, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14660, ts 638095651, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49757, ts 11040, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14661, ts 638095811, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49758, ts 11200, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14662, ts 638095971, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49759, ts 11360, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14663, ts 638096131, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49760, ts 11520, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14664, ts 638096291, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49761, ts 11680, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14665, ts 638096451, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49762, ts 11840, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14666, ts 638096611, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49763, ts 12000, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14667, ts 638096771, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49764, ts 12160, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14668, ts 638096931, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49765, ts 12320, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14669, ts 638097091, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49766, ts 12480, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14670, ts 638097251, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49767, ts 12640, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14671, ts 638097411, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49768, ts 12800, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14672, ts 638097571, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49769, ts 12960, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14673, ts 638097731, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49770, ts 13120, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14674, ts 638097891, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49771, ts 13280, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14675, ts 638098051, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14676, ts 638098211, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49772, ts 13440, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49773, ts 13600, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14677, ts 638098371, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49774, ts 13760, len 160) Sep 4 13:06:11 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14678, ts 638098531, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49775, ts 13920, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14679, ts 638098691, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49776, ts 14080, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14680, ts 638098851, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49777, ts 14240, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14681, ts 638099011, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49778, ts 14400, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14682, ts 638099171, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49779, ts 14560, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14683, ts 638099331, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49780, ts 14720, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14684, ts 638099491, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49781, ts 14880, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14685, ts 638099651, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49782, ts 15040, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14686, ts 638099811, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49783, ts 15200, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14687, ts 638099971, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49784, ts 15360, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14688, ts 638100131, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49785, ts 15520, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14689, ts 638100291, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49786, ts 15680, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14690, ts 638100451, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49787, ts 15840, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14691, ts 638100611, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49788, ts 16000, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14692, ts 638100771, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49789, ts 16160, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14693, ts 638100931, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49790, ts 16320, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14694, ts 638101091, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49791, ts 16480, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14695, ts 638101251, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49792, ts 16640, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14696, ts 638101411, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49793, ts 16800, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14697, ts 638101571, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49794, ts 16960, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14698, ts 638101731, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49795, ts 17120, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14699, ts 638101891, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49796, ts 17280, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14700, ts 638102051, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49797, ts 17440, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14701, ts 638102211, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49798, ts 17600, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14702, ts 638102371, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49799, ts 17760, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14703, ts 638102531, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49800, ts 17920, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14704, ts 638102691, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49801, ts 18080, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14705, ts 638102851, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49802, ts 18240, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14706, ts 638103011, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49803, ts 18400, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14707, ts 638103171, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49804, ts 18560, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14708, ts 638103331, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49805, ts 18720, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14709, ts 638103491, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14710, ts 638103651, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49806, ts 18880, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49807, ts 19040, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14711, ts 638103811, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14712, ts 638103971, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49808, ts 19200, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Sent RTP packet to 67.189.14.105:2264 (type 0, seq 49809, ts 19360, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14713, ts 638104131, len 160) Sep 4 13:06:12 DEBUG[30937] channel.c: Scheduling timer at 0 sample intervals Sep 4 13:06:12 DEBUG[30937] channel.c: Scheduling timer at 0 sample intervals Sep 4 13:06:12 DEBUG[30937] channel.c: Set channel SIP/354-a2cd to write format ulaw Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14714, ts 638104291, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14715, ts 638104451, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14716, ts 638104611, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14717, ts 638104771, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14718, ts 638104931, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14719, ts 638105091, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14720, ts 638105251, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14721, ts 638105411, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14722, ts 638105571, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14723, ts 638105731, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14724, ts 638105891, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14725, ts 638106051, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14726, ts 638106211, len 160) Sep 4 13:06:12 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14727, ts 638106371, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14728, ts 638106531, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14729, ts 638106691, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14730, ts 638106851, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14731, ts 638107011, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14732, ts 638107171, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14733, ts 638107331, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: Got RTP packet from 67.189.14.105:2264 (type 0, seq 14734, ts 638107491, len 160) Sep 4 13:06:13 VERBOSE[30937] logger.c: <-- SIP read from 67.189.14.105:5060: BYE sip:*98@67.189.77.121 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bKe172c975706BA830 From: "354" ;tag=A24512A-CBC1B2BF To: ;tag=as49bb38b3 CSeq: 2 BYE Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 Contact: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1 Max-Forwards: 70 Content-Length: 0 Sep 4 13:06:13 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:13 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:13 VERBOSE[30937] logger.c: Sending to 192.168.1.102 : 5060 (NAT) Sep 4 13:06:13 VERBOSE[30937] logger.c: Transmitting (NAT) to 67.189.14.105:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.102;branch=z9hG4bKe172c975706BA830;received=67.189.14.105;rport=5060 From: "354" ;tag=A24512A-CBC1B2BF To: ;tag=as49bb38b3 Call-ID: 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102 CSeq: 2 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Sep 4 13:06:13 WARNING[30937] app_voicemail.c: Couldn't read username Sep 4 13:06:13 DEBUG[30937] pbx.c: Extension *98, priority 3 returned normally even though call was hung up Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'from-internal-rt' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'from-internal-rt' AND priority = '1' ORDER BY exten Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1' Sep 4 13:06:13 DEBUG[30937] res_config_mysql.c: MySQL RealTime: Everything is fine. Sep 4 13:06:13 VERBOSE[30937] logger.c: -- Executing Hangup("SIP/354-a2cd", "") Sep 4 13:06:13 DEBUG[30937] pbx.c: Spawn extension (from-internal,h,1) exited non-zero on 'SIP/354-a2cd' Sep 4 13:06:13 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Sep 4 13:06:13 DEBUG[30937] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2005-09-04 13:06:09','\"External User\" <354>','354','*98','from-internal', 'SIP/354-a2cd','','Hangup','',4,4,'ANSWERED',3,'') Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is '"External User" <354>' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is '354' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is '*98' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is 'from-internal' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is 'SIP/354-a2cd' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is '(null)' Sep 4 13:06:13 DEBUG[30937] pbx.c: Function result is 'Hangup' Sep 4 13:06:13 NOTICE[30937] pbx.c: Error in extension logic (missing '}') Sep 4 13:06:13 ERROR[30937] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory Sep 4 13:06:13 ERROR[30937] cdr_csv.c: Unable to re-open master file /var/log/asterisk//cdr-csv//Master.csv : No such file or directory Sep 4 13:06:13 DEBUG[30937] channel.c: Hanging up channel 'SIP/354-a2cd' Sep 4 13:06:13 DEBUG[30937] chan_sip.c: Hangup call SIP/354-a2cd, SIP callid 9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102) Sep 4 13:06:13 DEBUG[30937] chan_sip.c: update_call_counter(354) - decrement call limit counter Sep 4 13:06:13 DEBUG[30937] app_queue.c: Device 'SIP/354' changed to state '1' (Not in use) Sep 4 13:06:14 DEBUG[30937] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Sep 4 13:06:14 DEBUG[30937] acl.c: ##### Testing 10.1.1.41 with 10.1.1.0 Sep 4 13:06:14 VERBOSE[30937] logger.c: 11 headers, 3 lines Sep 4 13:06:14 VERBOSE[30937] logger.c: Reliably Transmitting (no NAT) to 10.1.1.41:5060: NOTIFY sip:256@10.1.1.41 SIP/2.0 Via: SIP/2.0/UDP 10.1.1.12:5060;branch=z9hG4bK2a1cbd6d From: "asterisk" ;tag=as59cda431 To: Contact: Call-ID: 4b74f2bb702c6d1c3a303fe75078f151@10.1.1.12 CSeq: 102 NOTIFY User-Agent: Asterisk PBX Event: message-summary Content-Type: application/simple-message-summary Content-Length: 81 Message-Account: sip:asterisk@ Messages-Waiting: yes Voice-Message: 5/2 (0/0) --- Sep 4 13:06:14 VERBOSE[30937] logger.c: Scheduling destruction of call '4b74f2bb702c6d1c3a303fe75078f151@10.1.1.12' in 15000 ms Sep 4 13:06:14 VERBOSE[30937] logger.c: Destroying call '9c60ffee-4f6bfb3c-2ec203e1@192.168.1.102' Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 10.1.1.41:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.1.1.12:5060;branch=z9hG4bK2a1cbd6d From: "asterisk" ;tag=as59cda431 To: ;tag=155629E7-1D88B77C CSeq: 102 NOTIFY Call-ID: 4b74f2bb702c6d1c3a303fe75078f151@10.1.1.12 Contact: Event: message-summary User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.5.2.0054 Content-Length: 0 Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (10 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (10 headers 0 lines)--- Sep 4 13:06:14 DEBUG[30937] chan_sip.c: Stopping retransmission on '4b74f2bb702c6d1c3a303fe75078f151@10.1.1.12' of Request 102: Match Found Sep 4 13:06:14 VERBOSE[30937] logger.c: Destroying call '4b74f2bb702c6d1c3a303fe75078f151@10.1.1.12' Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: <-- SIP read from 66.165.175.35:5060: Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines)Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive Sep 4 13:06:14 VERBOSE[30937] logger.c: --- (0 headers 0 lines) Nat keepalive --- Sep 4 13:06:14 VERBOSE[30937] logger.c: Beginning asterisk shutdown.... Sep 4 13:06:14 VERBOSE[30937] logger.c: Executing last minute cleanups Sep 4 13:06:14 VERBOSE[30937] logger.c: == Destroying musiconhold processes Sep 4 13:06:14 DEBUG[30937] res_musiconhold.c: killing 30948! Sep 4 13:06:14 DEBUG[30937] res_musiconhold.c: mpg123 pid 30948 and child died after 2588160 bytes read Sep 4 13:06:14 DEBUG[30937] res_musiconhold.c: killing 30945! Sep 4 13:06:14 DEBUG[30937] res_musiconhold.c: mpg123 pid 30945 and child died after 4946944 bytes read Sep 4 13:06:14 VERBOSE[30937] logger.c: Asterisk cleanly ending (0).