Sep  4 13:08:43 VERBOSE[30977] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/dnsmgr.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c: Asterisk Dynamic Loader loading preload modules:
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/modules.conf
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/manager.conf
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/manager_custom.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c: Asterisk Management interface listening on port 5038
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/cdr.conf
Sep  4 13:08:43 NOTICE[30977] cdr.c: CDR simple logging enabled.
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/rtp.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c: Asterisk PBX Core Initializing
Sep  4 13:08:43 VERBOSE[30977] logger.c: Registering builtin applications:
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [AbsoluteTimeout]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Answer]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [BackGround]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Busy]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Congestion]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [DigitTimeout]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Goto]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [GotoIf]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [GotoIfTime]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [ExecIfTime]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Hangup]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [NoOp]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Prefix]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Progress]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [ResetCDR]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [ResponseTimeout]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Ringing]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SayNumber]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SayDigits]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SayAlpha]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SayPhonetic]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SetAccount]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SetAMAFlags]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SetGlobalVar]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SetLanguage]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Set]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [SetVar]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [ImportVar]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [StripMSD]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Suffix]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [Wait]
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [WaitExten]
Sep  4 13:08:43 VERBOSE[30977] logger.c: Asterisk Dynamic Loader Starting:
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/modules.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [app_realtime.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [app_realtime.so] => (Realtime Data Lookup/Rewrite)
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_odbc.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_odbc.so] => (ODBC Resource)
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/res_odbc.conf
Sep  4 13:08:43 NOTICE[30977] res_odbc.c: registered database handle 'asterisk' dsn->[default]
Sep  4 13:08:43 NOTICE[30977] res_odbc.c: Connecting asterisk
Sep  4 13:08:43 NOTICE[30977] res_odbc.c: res_odbc: Connected to asterisk [default]
Sep  4 13:08:43 NOTICE[30977] res_odbc.c: res_odbc loaded.
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem.so] => (Generic Voice Modem Driver)
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/modem.conf
Sep  4 13:08:43 DEBUG[30977] channel.c: Registered handler for 'Modem' (Generic Voice Modem Channel Driver)
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_musiconhold.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_musiconhold.so] => (Music On Hold Resource)
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/musiconhold.conf
Sep  4 13:08:43 WARNING[30977] 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:08:43 VERBOSE[30977] logger.c:  [res_adsi.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_adsi.so] => (ADSI Resource)
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/adsi.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_features.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_features.so] => (Call Features Resource)
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/features.conf
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/parking.conf
Sep  4 13:08:43 DEBUG[30977] pbx.c: Registered context 'parkedcalls'
Sep  4 13:08:43 DEBUG[30977] pbx.c: Added extension '700' priority 1 to parkedcalls
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_crypto.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_crypto.so] => (Cryptographic Digital Signatures)
Sep  4 13:08:43 DEBUG[30977] res_crypto.c: Key 'iaxtel' loaded OK
Sep  4 13:08:43 DEBUG[30977] res_crypto.c: Key 'freeworlddialup' loaded OK
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_indications.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_indications.so] => (Indications Configuration)
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/indications.conf
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_monitor.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_monitor.so] => (Call Monitoring Resource)
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_agi.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_agi.so] => (Asterisk Gateway Interface (AGI))
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_config_odbc.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_config_odbc.so] => (ODBC Configuration)
Sep  4 13:08:43 NOTICE[30977] config.c: Registered Config Engine odbc
Sep  4 13:08:43 VERBOSE[30977] logger.c: res_config_odbc loaded.
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_config_mysql.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [res_config_mysql.so] => (MySQL RealTime Configuration Driver)
Sep  4 13:08:43 DEBUG[30977] config.c: Parsing /etc/asterisk/res_mysql.conf
Sep  4 13:08:43 WARNING[30977] res_config_mysql.c: MySQL RealTime: No database port found, using 3306 as default.
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime Host: localhost
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime Port: 3306
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime User: asterisk
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime Password: asterisk
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Successfully connected to database.
Sep  4 13:08:43 NOTICE[30977] config.c: Registered Config Engine mysql
Sep  4 13:08:43 VERBOSE[30977] logger.c: MySQL RealTime driver loaded.
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_sip.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_sip.so] => (Session Initiation Protocol (SIP))
Sep  4 13:08:43 DEBUG[30977] channel.c: Registered handler for 'SIP' (Session Initiation Protocol (SIP))
Sep  4 13:08:43 DEBUG[30977] 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:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 30 rows.
Sep  4 13:08:43 DEBUG[30977] acl.c: 10.1.1.1/255.255.255.0/255.255.255.0 appended to acl for peer
Sep  4 13:08:43 DEBUG[30977] 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:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 2 rows.
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem_aopen.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem_aopen.so] => (A/Open (Rockwell Chipset) ITU-2 VoiceModem Driver)
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem_bestdata.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_modem_bestdata.so] => (BestData (Conexant V.90 Chipset) VoiceModem Driver)
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_agent.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_agent.so] => (Agent Proxy Channel)
Sep  4 13:08:43 DEBUG[30977] channel.c: Registered handler for 'Agent' (Call Agent Proxy Channel)
Sep  4 13:08:43 DEBUG[30977] config.c: No file to parse: /etc/asterisk/agents.conf
Sep  4 13:08:43 NOTICE[30977] chan_agent.c: No agent configuration found -- agent support disabled
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_mgcp.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_mgcp.so] => (Media Gateway Control Protocol (MGCP))
Sep  4 13:08:43 DEBUG[30977] 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:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 6 rows.
Sep  4 13:08:43 DEBUG[30977] channel.c: Registered handler for 'MGCP' (Media Gateway Control Protocol (MGCP))
Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_iax2.so]Sep  4 13:08:43 VERBOSE[30977] logger.c:  [chan_iax2.so] => (Inter Asterisk eXchange (Ver 2))
Sep  4 13:08:43 DEBUG[30977] 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:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:43 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 10 rows.
Sep  4 13:08:44 DEBUG[30977] channel.c: Registered handler for 'IAX2' (Inter Asterisk eXchange Driver (Ver 2))
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 7 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_local.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_local.so] => (Local Proxy Channel)
Sep  4 13:08:44 DEBUG[30977] channel.c: Registered handler for 'Local' (Local Proxy Channel Driver)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_skinny.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_skinny.so] => (Skinny Client Control Protocol (Skinny))
Sep  4 13:08:44 DEBUG[30977] config.c: No file to parse: /etc/asterisk/skinny.conf
Sep  4 13:08:44 NOTICE[30977] chan_skinny.c: Unable to load config skinny.conf, Skinny disabled
Sep  4 13:08:44 DEBUG[30977] channel.c: Registered handler for 'Skinny' (Skinny Client Control Protocol (Skinny))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_features.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_features.so] => (Feature Proxy Channel)
Sep  4 13:08:44 DEBUG[30977] channel.c: Registered handler for 'Feature' (Feature Proxy Channel Driver)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [skipping chan_oss.so]
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_modem_i4l.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_modem_i4l.so] => (ISDN4Linux Emulated Modem Driver)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [skipping chan_phone.so]
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_zap.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [chan_zap.so] => (Zapata Telephony w/PRI)
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/zapata.conf
Sep  4 13:08:44 DEBUG[30977] channel.c: Registered handler for 'Zap' (Zapata Telephony Driver w/PRI)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_config.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_config.so] => (Text Extension Configuration)
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_custom.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_realtime.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_macro.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_apps.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_callforward.conf
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/extensions_followme.conf
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'sales'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to sales
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'support'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to support
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'from-pstn'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to from-pstn
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 1 to from-pstn
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'T' priority 1 to from-pstn
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'fromvm'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'from-internal'
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to include switch 'Realtime/from-internal-rt@extensions' in context 'from-internal'
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to include context 'app-callforward' in context 'from-internal'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*77' priority 1 to from-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-stdexten'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-NOANSWER' priority 1 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-NOANSWER' priority 2 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-BUSY' priority 1 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-BUSY' priority 2 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_s-.' priority 1 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'a' priority 1 to macro-stdexten
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-goto-context-exten-prio'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-goto-context-exten-prio
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-dial'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 10 to macro-dial
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-place_local_or_pstn_call'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'local' priority 1 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'local' priority 2 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'local' priority 3 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'pstn' priority 1 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'pstn' priority 2 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'pstn' priority 3 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-place_local_or_pstn_call
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-calltrace'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 8 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 9 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 10 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 11 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 12 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 13 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 14 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '1' priority 1 to macro-calltrace
Sep  4 13:08:44 WARNING[30977] pbx.c: Unable to register extension '1', priority 1 in 'macro-calltrace', already in use
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to register extension at line 69
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'i' priority 1 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'i' priority 2 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 1 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 2 to macro-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-vm'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'o' priority 1 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'o' priority 2 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'a' priority 1 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'a' priority 2 to macro-vm
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-fixcid'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-fixcid
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-rg-group'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-rg-group
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-rg-group
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-rg-group
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-rg-group
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-rg-group
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-outisbusy'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-outisbusy
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-outisbusy
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-outisbusy
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-hangupcall'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-hangupcall
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-hangupcall
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-hangupcall
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-faxreceive'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-faxreceive
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-faxreceive
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-faxreceive
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 103 to macro-faxreceive
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 104 to macro-faxreceive
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-dialout'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 8 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 9 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 109 to macro-dialout
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-dialout-default'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'nocidnum' priority 1 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'nocidnum' priority 2 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'nocidname' priority 1 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'nocidname' priority 2 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-dialout-default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-dialout-default-disabled'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 8 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 9 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 10 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 108 to macro-dialout-default-disabled
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-agent-add'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-agent-add
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-agent-del'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-agent-del
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-agent-del
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-agent-del
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-agent-del
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-agent-del
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-changevmpass'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-changevmpass
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-directory'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '#' priority 1 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '#' priority 2 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '#' priority 3 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '#' priority 4 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*411' priority 1 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*411' priority 2 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*411' priority 3 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*411' priority 4 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*411' priority 5 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'h' priority 1 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'o' priority 1 to app-directory
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-dnd'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*78' priority 1 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*78' priority 2 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*78' priority 3 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*78' priority 4 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*78' priority 5 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*79' priority 1 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*79' priority 2 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*79' priority 3 to app-dnd
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*79' priority 4 to app-dnd
Sep  4 13:08:44 WARNING[30977] pbx.c: Unable to register extension '*79', priority 4 in 'app-dnd', already in use
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to register extension at line 29
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-voicemail'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*98' priority 1 to app-voicemail
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*69' priority 2 to app-voicemail
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*98' priority 3 to app-voicemail
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*98' priority 4 to app-voicemail
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-calltrace'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*69' priority 1 to app-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*69' priority 2 to app-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*69' priority 3 to app-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*69' priority 4 to app-calltrace
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'portable-extensions'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '6900' priority 1 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 1 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 2 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 3 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 4 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 5 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_X.' priority 104 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 1 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 2 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 3 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 4 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 5 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'testvm' priority 102 to portable-extensions
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'portable-extensions-6900'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 8 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 9 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 105 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 106 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'deactivate' priority 1 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'deactivate' priority 2 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'deactivate' priority 3 to portable-extensions-6900
Sep  4 13:08:44 WARNING[30977] pbx.c: Unable to register extension 'deactivate', priority 3 in 'portable-extensions-6900', already in use
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to register extension at line 78
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'deactivate' priority 103 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'deactivate' priority 104 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 1 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 3 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 4 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 5 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 6 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 7 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'gather' priority 8 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'commit' priority 1 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'commit' priority 2 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'commit' priority 3 to portable-extensions-6900
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-cancel_all'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 8 to macro-cancel_all
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-get-exten-advance'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 1 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 2 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 't' priority 3 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'T' priority 1 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'T' priority 2 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'T' priority 3 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'i' priority 1 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'i' priority 2 to macro-get-exten-advance
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-callforward'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 1 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 2 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 3 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 4 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 5 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 6 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 7 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 8 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 9 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*72.' priority 10 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 1 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 2 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 3 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 4 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 5 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 6 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 7 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 8 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 9 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 10 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 11 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 12 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 13 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*72' priority 14 to app-callforward
Sep  4 13:08:44 WARNING[30977] pbx.c: Unable to register extension '*72', priority 14 in 'app-callforward', already in use
Sep  4 13:08:44 WARNING[30977] pbx_config.c: Unable to register extension at line 58
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*73.' priority 1 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*73' priority 1 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 1 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 2 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 3 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 4 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 5 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 6 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 7 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 8 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 9 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*90.' priority 10 to app-callforward
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-dial-internal'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 5 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 6 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 7 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob' priority 4 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-busy' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-busy' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-busy' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-local' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-local' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-local' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-pstn' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cfob-pstn' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf-pstn' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf-pstn' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf-local' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf-local' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'cf-local' priority 3 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'fm' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'fm' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'hunt' priority 1 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'hunt' priority 2 to macro-dial-internal
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'macro-followme'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-NOANSWER' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's-BUSY' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 2 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 3 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 4 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 5 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 6 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 7 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 8 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 9 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 10 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'followme' priority 200 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'fm-NOANSWER' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'fm-BUSY' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_s-.' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'a' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'voicemail_noanswer' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'voicemail_noanswer' priority 2 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'voicemail_busy' priority 1 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'voicemail_busy' priority 2 to macro-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'app-followme'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 1 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 2 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 3 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 4 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 5 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 6 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 7 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 106 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 107 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 108 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 109 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 208 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 209 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 210 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 211 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 212 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 213 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 214 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*22' priority 215 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 1 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 2 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 3 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 4 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 5 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 6 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 7 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 8 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 9 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 10 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 11 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 12 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*21.' priority 13 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 1 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 2 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 3 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 4 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 5 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 6 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 7 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 8 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 9 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 10 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*21' priority 11 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 1 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 2 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 3 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 4 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 5 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_*23.' priority 6 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 1 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 2 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 3 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 4 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 5 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '*23' priority 6 to app-followme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'ext-meetme'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_8XX' priority 1 to ext-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_8XX' priority 2 to ext-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_8XX' priority 3 to ext-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_8XX' priority 4 to ext-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension '_8XX' priority 5 to ext-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'default'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to default
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'invalid'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to invalid
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 2 to invalid
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 3 to invalid
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 4 to invalid
Sep  4 13:08:44 DEBUG[30977] pbx.c: Registered context 'custom-meetme'
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 's' priority 1 to custom-meetme
Sep  4 13:08:44 DEBUG[30977] pbx.c: Added extension 'h' priority 1 to custom-meetme
Sep  4 13:08:44 WARNING[30977] pbx.c: Context 'from-internal' tries includes nonexistent context 'app-displayvar'
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_spool.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_spool.so] => (Outgoing Spool Support)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_dundi.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_dundi.so] => (Distributed Universal Number Discovery (DUNDi))
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/dundi.conf
Sep  4 13:08:44 DEBUG[30977] pbx_dundi.c: Seeding global EID '00:11:d8:d4:b2:b6' from 'eth0'
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_loopback.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_loopback.so] => (Loopback Switch)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_realtime.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_realtime.so] => (Realtime Switch)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_ael.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_ael.so] => (Asterisk Extension Language Compiler)
Sep  4 13:08:44 NOTICE[30977] pbx_ael.c: Unknown root token ''
Sep  4 13:08:44 WARNING[30977] pbx.c: Requested contexts didn't get merged
Sep  4 13:08:44 WARNING[30977] pbx.c: Context 'from-internal' tries includes nonexistent context 'app-displayvar'
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_functions.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [pbx_functions.so] => (Builtin dialplan functions)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [skipping chan_oss.so]
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [skipping chan_phone.so]
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dial.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dial.so] => (Dialing Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_playback.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_playback.so] => (Sound File Playback Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_voicemail.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_voicemail.so] => (Comedian Mail (Voicemail System))
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 13 rows.
Sep  4 13:08:44 DEBUG[30977] app_voicemail.c: VM CID Info before msg disabled globally
Sep  4 13:08:44 DEBUG[30977] app_voicemail.c: Send Voicemail msg disabled globally
Sep  4 13:08:44 DEBUG[30977] app_voicemail.c: ENVELOPE before msg enabled globally
Sep  4 13:08:44 DEBUG[30977] app_voicemail.c: Duration info before msg enabled globally
Sep  4 13:08:44 DEBUG[30977] app_voicemail.c: We are not going to skip to the next msg after save/delete
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_directory.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_directory.so] => (Extension Directory)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_mp3.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_mp3.so] => (Silly MP3 Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_system.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_system.so] => (Generic System() application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_echo.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_echo.so] => (Simple Echo Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_record.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_record.so] => (Trivial Record Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_image.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_image.so] => (Image Transmission Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_url.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_url.so] => (Send URL Applications)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_disa.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_disa.so] => (DISA (Direct Inward System Access) Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_adsiprog.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_adsiprog.so] => (Asterisk ADSI Programming Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_getcpeid.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_getcpeid.so] => (Get ADSI CPE ID)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_milliwatt.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_milliwatt.so] => (Digital Milliwatt (mu-law) Test Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapateller.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapateller.so] => (Block Telemarketers with Special Information Tone)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcallerid.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcallerid.so] => (Set CallerID Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_festival.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_festival.so] => (Simple Festival Interface)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_queue.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_queue.so] => (True Call Queueing)
Sep  4 13:08:44 DEBUG[30977] config.c: No file to parse: /etc/asterisk/queues.conf
Sep  4 13:08:44 NOTICE[30977] app_queue.c: No call queueing config file (queues.conf), so no call queues
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_senddtmf.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_senddtmf.so] => (Send DTMF digits Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_parkandannounce.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_parkandannounce.so] => (Call Parking and Announce Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_striplsd.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_striplsd.so] => (Strip trailing digits)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcidname.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcidname.so] => (Set CallerID Name)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_lookupcidname.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_lookupcidname.so] => (Look up CallerID Name from local database)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_substring.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_substring.so] => ((Deprecated) Save substring digits in a given variable)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_macro.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_macro.so] => (Extension Macros)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_authenticate.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_authenticate.so] => (Authentication Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_softhangup.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_softhangup.so] => (Hangs up the requested channel)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_lookupblacklist.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_lookupblacklist.so] => (Look up Caller*ID name/number from blacklist database)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_waitforring.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_waitforring.so] => (Waits until first ring after time)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_privacy.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_privacy.so] => (Require phone number to be entered, if no CallerID sent)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_db.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_db.so] => (Database access functions for Asterisk extension logic)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_chanisavail.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_chanisavail.so] => (Check if channel is available)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_enumlookup.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_enumlookup.so] => (ENUM Lookup)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 2 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_transfer.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_transfer.so] => (Transfer)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcidnum.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcidnum.so] => (Set CallerID Number)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_cdr.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_cdr.so] => (Make sure asterisk doesn't save CDR for a certain call)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_hasnewvoicemail.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_hasnewvoicemail.so] => (Indicator for whether a voice mailbox has messages in a given folder.[)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sayunixtime.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sayunixtime.so] => (Say time)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_cut.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_cut.so] => (String manipulation)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_read.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_read.so] => (Read Variable Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcdruserfield.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setcdruserfield.so] => (CDR user field apps)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_random.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_random.so] => (Random goto)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_ices.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_ices.so] => (Encode and Stream via icecast and ices)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_eval.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_eval.so] => (Reevaluates strings)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_nbscat.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_nbscat.so] => (Silly NBS Stream Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sendtext.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sendtext.so] => (Send Text Applications)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_exec.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_exec.so] => (Executes applications)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sms.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_sms.so] => (SMS/PSTN handler)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_groupcount.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_groupcount.so] => (Group Management Routines)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_txtcidname.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_txtcidname.so] => (TXTCIDName)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 2 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_controlplayback.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_controlplayback.so] => (Control Playback Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_talkdetect.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_talkdetect.so] => (Playback with Talk Detection)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_alarmreceiver.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_alarmreceiver.so] => (Alarm Receiver for Asterisk)
Sep  4 13:08:44 DEBUG[30977] config.c: No file to parse: /etc/asterisk/alarmreceiver.conf
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_userevent.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_userevent.so] => (Custom User Event Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_verbose.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_verbose.so] => (Send verbose output)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_test.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_test.so] => (Interface Test Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_forkcdr.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_forkcdr.so] => (Fork The CDR into 2 separate entities.)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_math.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_math.so] => (Basic Math Functions)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dumpchan.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dumpchan.so] => (Dump Info About The Calling Channel)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_waitforsilence.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_waitforsilence.so] => (Wait For Silence)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_while.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_while.so] => (While Loops and Conditional Execution)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setrdnis.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_setrdnis.so] => (Set RDNIS Number)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_md5.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_md5.so] => (MD5 checksum applications)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_readfile.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_readfile.so] => (Stores output of file into a variable)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_chanspy.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_chanspy.so] => (Tap into any type of asterisk channel and listen to audio)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_settransfercapability.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_settransfercapability.so] => (Set ISDN Transfer Capability)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dictate.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_dictate.so] => (Virtual Dictation Machine)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapras.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapras.so] => (Zap RAS Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_meetme.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_meetme.so] => (MeetMe conference bridge)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_flash.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_flash.so] => (Flash zap trunk application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapbarge.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapbarge.so] => (Barge in on Zap channel application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapscan.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_zapscan.so] => (Scan Zap channels application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_ilbc.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_ilbc.so] => (iLBC/PCM16 (signed linear) Codec Translator)
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_gsm.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_gsm.so] => (GSM/PCM16 (signed linear) Codec Translator)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_lpc10.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_lpc10.so] => (LPC10 2.4kbps (signed linear) Voice Coder)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_adpcm.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_adpcm.so] => (Adaptive Differential PCM Coder/Decoder)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_ulaw.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_ulaw.so] => (Mu-law Coder/Decoder)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_alaw.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_alaw.so] => (A-law Coder/Decoder)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_a_mu.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_a_mu.so] => (A-law and Mulaw direct Coder/Decoder)
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_g726.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_g726.so] => (ITU G.726-32kbps G726 Transcoder)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 9 rows.
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from ulaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 18 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 25 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from g723 to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from gsm to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 13 cost path from unknown to ulaw, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from ulaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 18 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 25 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 14 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_gsm.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_gsm.so] => (Raw GSM data)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_wav.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_wav.so] => (Microsoft WAV format (8000hz Signed Linear))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_wav_gsm.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_wav_gsm.so] => (Microsoft WAV format (Proprietary GSM))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_vox.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_vox.so] => (Dialogic VOX (ADPCM) File Format)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_pcm.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_pcm.so] => (Raw uLaw 8khz Audio support (PCM))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g729.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g729.so] => (Raw G729 data)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_pcm_alaw.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_pcm_alaw.so] => (Raw aLaw 8khz PCM Audio support)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_h263.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_h263.so] => (Raw h263 data)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g726.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g726.so] => (Raw G.726 (16/24/32/40kbps) data)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_ilbc.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_ilbc.so] => (Raw iLBC data)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_sln.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_sln.so] => (Raw Signed Linear Audio support (SLN))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_au.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_au.so] => (Sun Microsystems AU format (signed linear))
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_jpeg.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_jpeg.so] => (JPEG (Joint Picture Experts Group) Image Format)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g723.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_g723.so] => (G.723.1 Simple Timestamp File Format)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_csv.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_csv.so] => (Comma Separated Values CDR Backend)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_manager.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_manager.so] => (Asterisk Call Manager CDR Backend)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 1 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_custom.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_custom.so] => (Customizable Comma Separated Values CDR Backend)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 1 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_odbc.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_odbc.so] => (ODBC CDR Backend)
Sep  4 13:08:44 DEBUG[30977] config.c: Parsing /etc/asterisk/cdr_odbc.conf
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [func_callerid.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [func_callerid.so] => (Caller ID related dialplan function)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_mp3.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [format_mp3.so] => (MP3 format [Any rate but 8000hz mono optimal])
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_saycountpl.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_saycountpl.so] => (Say polish counting words)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_addon_mysql.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [cdr_addon_mysql.so] => (MySQL CDR Backend)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 6 rows.
Sep  4 13:08:44 NOTICE[30977] cdr_addon_mysql.c: MySQL database table not specified.  Assuming "cdr"
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got hostname of localhost
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got port of 3306
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got sock file of /var/lib/mysql/mysql.sock
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got user of asterisk
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got dbname of asterisk_realtime
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: got password of asterisk
Sep  4 13:08:44 DEBUG[30977] cdr_addon_mysql.c: Successfully connected to MySQL database.
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_addon_sql_mysql.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_addon_sql_mysql.so] => (Simple Mysql Interface)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_g729.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [codec_g729.so] => (G729/PCM16 (signed linear) Codec Translator, based on IPP)
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from g723 to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from gsm to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 13 cost path from unknown to ulaw, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from ulaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 18 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 25 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from alaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 13 cost path from alaw to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 14 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Reseting translation matrix
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from g723 to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from g723 to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from g723 to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from gsm to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from gsm to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from gsm to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from gsm to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to g723, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 13 cost path from unknown to ulaw, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to alaw, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from unknown to unknown, via 2
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from ulaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 18 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 18 cost path from ulaw to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 25 cost path from ulaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 8 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 2 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from unknown to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 16 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 15 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 5 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 12 cost path from unknown to alaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 19 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 9 cost path from alaw to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 13 cost path from alaw to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 3 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 17 cost path from alaw to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 10 cost path from unknown to g723, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to gsm, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 14 cost path from unknown to ulaw, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 4 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to unknown, via 6
Sep  4 13:08:44 DEBUG[30977] translate.c: Discovered 11 cost path from unknown to alaw, via 6
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_externalivr.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [app_externalivr.so] => (External IVR Interface Application)
Sep  4 13:08:44 VERBOSE[30977] logger.c:  [func_uri.so]Sep  4 13:08:44 VERBOSE[30977] logger.c:  [func_uri.so] => (URI encode/decode functions)
Sep  4 13:08:44 DEBUG[30977] 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:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Found 2 rows.
Sep  4 13:08:44 VERBOSE[30977] logger.c: Asterisk Ready.
Sep  4 13:08:44 VERBOSE[30977] logger.c: Asterisk Ready.
Asterisk Malloc Debugger Started (see /var/log/asterisk/mmlog))
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 004d0c3f25391cde0b90386f7914591e@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #15 
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Stopping retransmission on '004d0c3f25391cde0b90386f7914591e@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Stopping retransmission on '004d0c3f25391cde0b90386f7914591e@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Cancelling timeout 15
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 78659cb5447352bf4a4b33a14cf3aa5e@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:44 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:44 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:44 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #20 
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Stopping retransmission on '78659cb5447352bf4a4b33a14cf3aa5e@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Stopping retransmission on '78659cb5447352bf4a4b33a14cf3aa5e@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Cancelling timeout 20
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 6c09488d6e80b08d7ac3f7800f756ff6@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:45 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:45 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:45 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #25 
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Stopping retransmission on '6c09488d6e80b08d7ac3f7800f756ff6@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Stopping retransmission on '6c09488d6e80b08d7ac3f7800f756ff6@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:45 DEBUG[30977] chan_sip.c: Cancelling timeout 25
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 465a6e08395e1d4c57fed5123688aa83@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:46 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #30 
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 7223a2233d9a41d61d0f01ab36932794@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:46 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #32 
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '465a6e08395e1d4c57fed5123688aa83@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '7223a2233d9a41d61d0f01ab36932794@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '465a6e08395e1d4c57fed5123688aa83@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Cancelling timeout 30
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 7722f76572251d1e0594e1ed4979753d@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:46 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:46 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #38 
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '7223a2233d9a41d61d0f01ab36932794@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Cancelling timeout 32
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '7722f76572251d1e0594e1ed4979753d@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Stopping retransmission on '7722f76572251d1e0594e1ed4979753d@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:46 DEBUG[30977] chan_sip.c: Cancelling timeout 38
Sep  4 13:08:48 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448063@66.165.175.35
Sep  4 13:08:48 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 79d42e5670bfc0a673b6700474037c43@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:48 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:48 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:48 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:48 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:48 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #45 
Sep  4 13:08:48 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:48 VERBOSE[30977] 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=z9hG4bK04ddb38e
From: <sip:19712448063@66.165.175.35>;tag=as24ccd190
To: <sip:19712448063@66.165.175.35>
Call-ID: 79d42e5670bfc0a673b6700474037c43@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448063@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:48 VERBOSE[30977] 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=z9hG4bK04ddb38e
From: <sip:19712448063@66.165.175.35>;tag=as24ccd190
To: <sip:19712448063@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-c529
Call-ID: 79d42e5670bfc0a673b6700474037c43@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557c14b90fd32f9db1c80ce9d9cb81cdef75"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:48 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:48 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:48 DEBUG[30977] chan_sip.c: Stopping retransmission on '79d42e5670bfc0a673b6700474037c43@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:48 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:48 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:48 VERBOSE[30977] 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=z9hG4bK4c6cd244
From: <sip:19712448063@66.165.175.35>;tag=as099d1f95
To: <sip:19712448063@66.165.175.35>
Call-ID: 79d42e5670bfc0a673b6700474037c43@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448063", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557c14b90fd32f9db1c80ce9d9cb81cdef75", response="3c17eb867b9049ad593510c08a3a04a9", opaque=""
Expires: 120
Contact: <sip:19712448063@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK4c6cd244
From: <sip:19712448063@66.165.175.35>;tag=as099d1f95
To: <sip:19712448063@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-452b
Call-ID: 79d42e5670bfc0a673b6700474037c43@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448063@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '79d42e5670bfc0a673b6700474037c43@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Cancelling timeout 45
Sep  4 13:08:49 VERBOSE[30977] logger.c: Scheduling destruction of call '79d42e5670bfc0a673b6700474037c43@10.1.1.12' in 32000 ms
Sep  4 13:08:49 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:49 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448060@66.165.175.35
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #50 
Sep  4 13:08:49 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK7fc16601
From: <sip:19712448060@66.165.175.35>;tag=as3e8cffb0
To: <sip:19712448060@66.165.175.35>
Call-ID: 37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448060@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK7e846c13168213DE
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>
CSeq: 1 INVITE
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
Contact: <sip:354@192.168.1.102>
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=- 1119300656 1119300656 IN IP4 192.168.1.102
s=Polycom IP Phone
c=IN IP4 192.168.1.102
t=0 0
m=audio 2266 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:08:49 VERBOSE[30977] logger.c: --- (14 headers 10 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (14 headers 10 lines)---
Sep  4 13:08:49 DEBUG[30977] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 12086851-db20cc2f-892dac2c@192.168.1.102 - INVITE (With RTP)
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: * SIP extension value: 2 for call 12086851-db20cc2f-892dac2c@192.168.1.102
Sep  4 13:08:49 VERBOSE[30977] logger.c: Using INVITE request as basis request - 12086851-db20cc2f-892dac2c@192.168.1.102
Sep  4 13:08:49 VERBOSE[30977] logger.c: Sending to 192.168.1.102 : 5060 (non-NAT)
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '354'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE ipaddr = '67.189.14.105'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
Sep  4 13:08:49 DEBUG[30977] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip
Sep  4 13:08:49 VERBOSE[30977] logger.c: 11 headers, 0 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK2d3b4a2b
From: "asterisk" <sip:asterisk@67.189.77.121>;tag=as1b442578
To: <sip:354@192.168.1.102>
Contact: <sip:asterisk@67.189.77.121>
Call-ID: 3fa8529d245d44ad2a3d8d6413263de5@67.189.77.121
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Date: Sun, 04 Sep 2005 20:08:49 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


---
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found peer '354'
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Setting NAT on RTP to 524288
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found RTP audio format 0
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found RTP audio format 8
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found RTP audio format 18
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found RTP audio format 101
Sep  4 13:08:49 VERBOSE[30977] logger.c: Peer audio RTP is at port 192.168.1.102:2266
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Peer audio RTP is at port 192.168.1.102:2266
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found description format PCMU
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found description format PCMA
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found description format G729
Sep  4 13:08:49 VERBOSE[30977] logger.c: Found description format telephone-event
Sep  4 13:08:49 VERBOSE[30977] 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:08:49 VERBOSE[30977] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Checking SIP call limits for device 354
Sep  4 13:08:49 VERBOSE[30977] logger.c: Looking for *98 in from-internal
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: build_route: Contact hop: <sip:354@192.168.1.102>
Sep  4 13:08:49 VERBOSE[30977] logger.c: list_route: hop: <sip:354@192.168.1.102>
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK7e846c13168213DE;received=67.189.14.105;rport=5060
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:*98@67.189.77.121>
Content-Length: 0


---
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '1'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448053@66.165.175.35
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 59b8f7261041a1aa40f71c753cc61e53@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] app_queue.c: Device 'SIP/354' changed to state '2' (In use)
Sep  4 13:08:49 DEBUG[30977] channel.c: Avoiding initial deadlock for 'SIP/354-2214'
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: sip_answer(SIP/354-2214)
Sep  4 13:08:49 VERBOSE[30977] logger.c: We're at 67.189.77.121 port 22308
Sep  4 13:08:49 VERBOSE[30977] logger.c: Answering with preferred capability 0x4 (ulaw)
Sep  4 13:08:49 VERBOSE[30977] logger.c: Answering with preferred capability 0x8 (alaw)
Sep  4 13:08:49 VERBOSE[30977] logger.c: Answering with preferred capability 0x100 (g729)
Sep  4 13:08:49 VERBOSE[30977] logger.c: Answering with preferred capability 0x2 (gsm)
Sep  4 13:08:49 VERBOSE[30977] logger.c: Answering with non-codec capability 0x1 (telephone-event)
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK7e846c13168213DE;received=67.189.14.105;rport=5060
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>;tag=as485c04c8
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:*98@67.189.77.121>
Content-Type: application/sdp
Content-Length: 291

v=0
o=root 30977 30977 IN IP4 67.189.77.121
s=session
c=IN IP4 67.189.77.121
t=0 0
m=audio 22308 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:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] 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:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '2'
Sep  4 13:08:49 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:49 DEBUG[30977] app_queue.c: Device 'SIP/354' changed to state '2' (In use)
Sep  4 13:08:49 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #57 
Sep  4 13:08:49 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK51c2647a
From: <sip:19712448053@66.165.175.35>;tag=as672de2e4
To: <sip:19712448053@66.165.175.35>
Call-ID: 59b8f7261041a1aa40f71c753cc61e53@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448053@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
Sep  4 13:08:49 DEBUG[30977] acl.c: ##### Testing 67.189.14.105 with 10.1.1.0
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Target address 67.189.14.105 is not local, substituting externip
Sep  4 13:08:49 VERBOSE[30977] logger.c: 11 headers, 3 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK41d72665;rport
From: "asterisk" <sip:asterisk@67.189.77.121>;tag=as786a4df9
To: <sip:354@192.168.1.102>
Contact: <sip:asterisk@67.189.77.121>
Call-ID: 609d045d0618a85865782fac0f26caca@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:08:49 VERBOSE[30977] logger.c: Scheduling destruction of call '609d045d0618a85865782fac0f26caca@67.189.77.121' in 15000 ms
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK2d3b4a2b
From: "asterisk" <sip:asterisk@67.189.77.121>;tag=as1b442578
To: <sip:354@192.168.1.102>;tag=D069CCD6-1A2E22CB
CSeq: 102 OPTIONS
Call-ID: 3fa8529d245d44ad2a3d8d6413263de5@67.189.77.121
Contact: <sip:354@192.168.1.102>
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:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '3fa8529d245d44ad2a3d8d6413263de5@67.189.77.121' of Request 102: Match Found
Sep  4 13:08:49 VERBOSE[30977] logger.c: Destroying call '3fa8529d245d44ad2a3d8d6413263de5@67.189.77.121'
Sep  4 13:08:49 DEBUG[30977] app_queue.c: Device 'SIP/354' changed to state '2' (In use)
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK7fc16601
From: <sip:19712448060@66.165.175.35>;tag=as3e8cffb0
To: <sip:19712448060@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-7e04
Call-ID: 37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557d2349115584fe22e95ffc6223e2c9becc"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:49 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:49 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK37fbbcfc
From: <sip:19712448060@66.165.175.35>;tag=as5615bddf
To: <sip:19712448060@66.165.175.35>
Call-ID: 37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448060", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557d2349115584fe22e95ffc6223e2c9becc", response="b2c98c7c6431dbe8a482e5a910c63659", opaque=""
Expires: 120
Contact: <sip:19712448060@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK51c2647a
From: <sip:19712448053@66.165.175.35>;tag=as672de2e4
To: <sip:19712448053@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-6a94
Call-ID: 59b8f7261041a1aa40f71c753cc61e53@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557d2349115584fe22e95ffc6223e2c9becc"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '59b8f7261041a1aa40f71c753cc61e53@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:49 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:49 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK0642b618
From: <sip:19712448053@66.165.175.35>;tag=as17493228
To: <sip:19712448053@66.165.175.35>
Call-ID: 59b8f7261041a1aa40f71c753cc61e53@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448053", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557d2349115584fe22e95ffc6223e2c9becc", response="5a313d2b406b7f534498cca2d6c8f9fb", opaque=""
Expires: 120
Contact: <sip:19712448053@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bKd2ec83a4E05D5FE7
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>;tag=as485c04c8
CSeq: 1 ACK
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
Contact: <sip:354@192.168.1.102>
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:08:49 VERBOSE[30977] logger.c: --- (11 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (11 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '12086851-db20cc2f-892dac2c@192.168.1.102' of Response 1: Match Found
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK37fbbcfc
From: <sip:19712448060@66.165.175.35>;tag=as5615bddf
To: <sip:19712448060@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-e1c2
Call-ID: 37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448060@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Cancelling timeout 50
Sep  4 13:08:49 VERBOSE[30977] logger.c: Scheduling destruction of call '37dcfd3c74f665fa3768dc9a2fce7950@10.1.1.12' in 32000 ms
Sep  4 13:08:49 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK0642b618
From: <sip:19712448053@66.165.175.35>;tag=as17493228
To: <sip:19712448053@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-9bcc
Call-ID: 59b8f7261041a1aa40f71c753cc61e53@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448053@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '59b8f7261041a1aa40f71c753cc61e53@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Cancelling timeout 57
Sep  4 13:08:49 VERBOSE[30977] logger.c: Scheduling destruction of call '59b8f7261041a1aa40f71c753cc61e53@10.1.1.12' in 32000 ms
Sep  4 13:08:49 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:49 VERBOSE[30977] 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=z9hG4bK41d72665;rport
From: "asterisk" <sip:asterisk@67.189.77.121>;tag=as786a4df9
To: <sip:354@192.168.1.102>;tag=7647BE5-146EBD12
CSeq: 102 NOTIFY
Call-ID: 609d045d0618a85865782fac0f26caca@67.189.77.121
Contact: <sip:354@192.168.1.102>
Event: message-summary
User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1
Content-Length: 0


Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:49 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:49 DEBUG[30977] chan_sip.c: Stopping retransmission on '609d045d0618a85865782fac0f26caca@67.189.77.121' of Request 102: Match Found
Sep  4 13:08:49 VERBOSE[30977] logger.c: Destroying call '609d045d0618a85865782fac0f26caca@67.189.77.121'
Sep  4 13:08:50 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448050@66.165.175.35
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 3027b332579351481d7cc5ab0f5b4e56@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #68 
Sep  4 13:08:50 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK15f43304
From: <sip:19712448050@66.165.175.35>;tag=as7186d0fd
To: <sip:19712448050@66.165.175.35>
Call-ID: 3027b332579351481d7cc5ab0f5b4e56@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448050@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:50 DEBUG[30977] 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:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] 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:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] 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:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] 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:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = '*98' AND context = 'features' AND priority = '3'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM voicemail WHERE mailbox = 'default' AND context = 'default'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 DEBUG[30977] channel.c: Set channel SIP/354-2214 to write format gsm
Sep  4 13:08:50 DEBUG[30977] rtp.c: Ooh, format changed from unknown to ulaw
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57776, ts 160, len 160)
Sep  4 13:08:50 DEBUG[30977] channel.c: Scheduling timer at 160 sample intervals
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57777, ts 320, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57778, ts 480, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57779, ts 640, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57780, ts 800, len 160)
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK15f43304
From: <sip:19712448050@66.165.175.35>;tag=as7186d0fd
To: <sip:19712448050@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-365b
Call-ID: 3027b332579351481d7cc5ab0f5b4e56@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557e9244eb795d78298549656944b416ebdd"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Stopping retransmission on '3027b332579351481d7cc5ab0f5b4e56@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:50 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:50 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK76c92408
From: <sip:19712448050@66.165.175.35>;tag=as4a007dea
To: <sip:19712448050@66.165.175.35>
Call-ID: 3027b332579351481d7cc5ab0f5b4e56@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448050", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557e9244eb795d78298549656944b416ebdd", response="c0b3780f37a0d2c92a93d4aa7e6598b6", opaque=""
Expires: 120
Contact: <sip:19712448050@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57781, ts 960, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57782, ts 1120, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57783, ts 1280, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57784, ts 1440, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57785, ts 1600, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57786, ts 1760, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57787, ts 1920, len 160)
Sep  4 13:08:50 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448055@66.165.175.35
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 0809e89c25eb134b140199f21d9eb987@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57788, ts 2080, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57789, ts 2240, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57790, ts 2400, len 160)
Sep  4 13:08:50 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #71 
Sep  4 13:08:50 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK757365d9
From: <sip:19712448055@66.165.175.35>;tag=as1e2d188e
To: <sip:19712448055@66.165.175.35>
Call-ID: 0809e89c25eb134b140199f21d9eb987@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448055@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK76c92408
From: <sip:19712448050@66.165.175.35>;tag=as4a007dea
To: <sip:19712448050@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-d923
Call-ID: 3027b332579351481d7cc5ab0f5b4e56@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:109.56 currency:USD
Contact: <sip:19712448050@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Stopping retransmission on '3027b332579351481d7cc5ab0f5b4e56@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Cancelling timeout 68
Sep  4 13:08:50 VERBOSE[30977] logger.c: Scheduling destruction of call '3027b332579351481d7cc5ab0f5b4e56@10.1.1.12' in 32000 ms
Sep  4 13:08:50 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57791, ts 2560, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57792, ts 2720, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57793, ts 2880, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57794, ts 3040, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57795, ts 3200, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57796, ts 3360, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57797, ts 3520, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57798, ts 3680, len 160)
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK757365d9
From: <sip:19712448055@66.165.175.35>;tag=as1e2d188e
To: <sip:19712448055@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-8bb0
Call-ID: 0809e89c25eb134b140199f21d9eb987@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557e9244eb795d78298549656944b416ebdd"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:50 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Stopping retransmission on '0809e89c25eb134b140199f21d9eb987@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:50 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:50 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:50 VERBOSE[30977] 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=z9hG4bK1befc55b
From: <sip:19712448055@66.165.175.35>;tag=as7a5ad473
To: <sip:19712448055@66.165.175.35>
Call-ID: 0809e89c25eb134b140199f21d9eb987@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448055", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557e9244eb795d78298549656944b416ebdd", response="a09c42ae0eac3ad3283b46c2a82b6030", opaque=""
Expires: 120
Contact: <sip:19712448055@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57799, ts 3840, len 160)
Sep  4 13:08:50 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448061@66.165.175.35
Sep  4 13:08:50 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:50 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57800, ts 4000, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57801, ts 4160, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57802, ts 4320, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57803, ts 4480, len 160)
Sep  4 13:08:50 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57804, ts 4640, len 160)
Sep  4 13:08:51 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #76 
Sep  4 13:08:51 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK167b469f
From: <sip:19712448061@66.165.175.35>;tag=as184b7821
To: <sip:19712448061@66.165.175.35>
Call-ID: 2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448061@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:51 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448059@66.165.175.35
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 16b0add931f4d3523bb9a957404276dd@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:51 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:51 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57805, ts 4800, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57806, ts 4960, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57807, ts 5120, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57808, ts 5280, len 160)
Sep  4 13:08:51 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #78 
Sep  4 13:08:51 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK60ac84f5
From: <sip:19712448059@66.165.175.35>;tag=as7543de0f
To: <sip:19712448059@66.165.175.35>
Call-ID: 16b0add931f4d3523bb9a957404276dd@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448059@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK1befc55b
From: <sip:19712448055@66.165.175.35>;tag=as7a5ad473
To: <sip:19712448055@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-7e52
Call-ID: 0809e89c25eb134b140199f21d9eb987@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448055@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Stopping retransmission on '0809e89c25eb134b140199f21d9eb987@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Cancelling timeout 71
Sep  4 13:08:51 VERBOSE[30977] logger.c: Scheduling destruction of call '0809e89c25eb134b140199f21d9eb987@10.1.1.12' in 32000 ms
Sep  4 13:08:51 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57809, ts 5440, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57810, ts 5600, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57811, ts 5760, len 160)
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK167b469f
From: <sip:19712448061@66.165.175.35>;tag=as184b7821
To: <sip:19712448061@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-eafd
Call-ID: 2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Stopping retransmission on '2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:51 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:51 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK00d6063d
From: <sip:19712448061@66.165.175.35>;tag=as038702bd
To: <sip:19712448061@66.165.175.35>
Call-ID: 2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448061", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93", response="ee6ce7e562c79fc9eb4c8135bc123464", opaque=""
Expires: 120
Contact: <sip:19712448061@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57812, ts 5920, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57813, ts 6080, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57814, ts 6240, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57815, ts 6400, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57816, ts 6560, len 160)
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK60ac84f5
From: <sip:19712448059@66.165.175.35>;tag=as7543de0f
To: <sip:19712448059@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-f1a5
Call-ID: 16b0add931f4d3523bb9a957404276dd@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Stopping retransmission on '16b0add931f4d3523bb9a957404276dd@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:51 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:51 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK02687acb
From: <sip:19712448059@66.165.175.35>;tag=as610adc2a
To: <sip:19712448059@66.165.175.35>
Call-ID: 16b0add931f4d3523bb9a957404276dd@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448059", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93", response="8bc22ca19215eecd820a853f62f91573", opaque=""
Expires: 120
Contact: <sip:19712448059@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57817, ts 6720, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57818, ts 6880, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57819, ts 7040, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57820, ts 7200, len 160)
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK00d6063d
From: <sip:19712448061@66.165.175.35>;tag=as038702bd
To: <sip:19712448061@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-c3eb
Call-ID: 2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448061@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Stopping retransmission on '2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Cancelling timeout 76
Sep  4 13:08:51 VERBOSE[30977] logger.c: Scheduling destruction of call '2d29a03338aa4a4116f2ff71213ba0e4@10.1.1.12' in 32000 ms
Sep  4 13:08:51 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57821, ts 7360, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57822, ts 7520, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57823, ts 7680, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57824, ts 7840, len 160)
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK02687acb
From: <sip:19712448059@66.165.175.35>;tag=as610adc2a
To: <sip:19712448059@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-e0e4
Call-ID: 16b0add931f4d3523bb9a957404276dd@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448059@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:51 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Stopping retransmission on '16b0add931f4d3523bb9a957404276dd@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Cancelling timeout 78
Sep  4 13:08:51 VERBOSE[30977] logger.c: Scheduling destruction of call '16b0add931f4d3523bb9a957404276dd@10.1.1.12' in 32000 ms
Sep  4 13:08:51 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57825, ts 8000, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57826, ts 8160, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57827, ts 8320, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57828, ts 8480, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57829, ts 8640, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57830, ts 8800, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57831, ts 8960, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57832, ts 9120, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57833, ts 9280, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57834, ts 9440, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57835, ts 9600, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57836, ts 9760, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57837, ts 9920, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57838, ts 10080, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57839, ts 10240, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57840, ts 10400, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57841, ts 10560, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57842, ts 10720, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57843, ts 10880, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57844, ts 11040, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57845, ts 11200, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57846, ts 11360, len 160)
Sep  4 13:08:51 NOTICE[30977] chan_sip.c:    -- Re-registration for  19712448056@66.165.175.35
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Allocating new SIP dialog for 3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12 - REGISTER (No RTP)
Sep  4 13:08:51 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM users WHERE name = '66.165.175.35'
Sep  4 13:08:51 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57847, ts 11520, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57848, ts 11680, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57849, ts 11840, len 160)
Sep  4 13:08:51 DEBUG[30977] acl.c: ##### Testing 66.165.175.35 with 10.1.1.0
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Target address 66.165.175.35 is not local, substituting externip
Sep  4 13:08:51 DEBUG[30977] chan_sip.c: Scheduled a registration timeout for 66.165.175.35 id  #88 
Sep  4 13:08:51 VERBOSE[30977] logger.c: REGISTER 11 headers, 0 lines
Sep  4 13:08:51 VERBOSE[30977] 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=z9hG4bK74fa08ae
From: <sip:19712448056@66.165.175.35>;tag=as26b4f57c
To: <sip:19712448056@66.165.175.35>
Call-ID: 3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12
CSeq: 102 REGISTER
User-Agent: Asterisk PBX
Expires: 120
Contact: <sip:19712448056@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57850, ts 12000, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57851, ts 12160, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57852, ts 12320, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57853, ts 12480, len 160)
Sep  4 13:08:51 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57854, ts 12640, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57855, ts 12800, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57856, ts 12960, len 160)
Sep  4 13:08:52 VERBOSE[30977] 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=z9hG4bK74fa08ae
From: <sip:19712448056@66.165.175.35>;tag=as26b4f57c
To: <sip:19712448056@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-971e
Call-ID: 3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12
CSeq: 102 REGISTER
WWW-Authenticate: Digest realm="66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93"
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:52 VERBOSE[30977] logger.c: --- (9 headers 0 lines)Sep  4 13:08:52 VERBOSE[30977] logger.c: --- (9 headers 0 lines)---
Sep  4 13:08:52 DEBUG[30977] chan_sip.c: Stopping retransmission on '3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12' of Request 102: Match Found
Sep  4 13:08:52 VERBOSE[30977] logger.c: Responding to challenge, registration to domain/host name 66.165.175.35
Sep  4 13:08:52 VERBOSE[30977] logger.c: REGISTER 12 headers, 0 lines
Sep  4 13:08:52 VERBOSE[30977] 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=z9hG4bK73fc0745
From: <sip:19712448056@66.165.175.35>;tag=as05be8e95
To: <sip:19712448056@66.165.175.35>
Call-ID: 3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12
CSeq: 103 REGISTER
User-Agent: Asterisk PBX
Authorization: Digest username="19712448056", realm="66.165.175.35", algorithm=MD5, uri="sip:66.165.175.35", nonce="431b557f8c7a78c37fd714cf14baea4af2e13b93", response="662e69541f28ec137527154ea67773b7", opaque=""
Expires: 120
Contact: <sip:19712448056@67.189.77.121>
Event: registration
Content-Length: 0


---
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57857, ts 13120, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57858, ts 13280, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57859, ts 13440, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57860, ts 13600, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57861, ts 13760, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57862, ts 13920, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57863, ts 14080, len 160)
Sep  4 13:08:52 VERBOSE[30977] 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=z9hG4bK73fc0745
From: <sip:19712448056@66.165.175.35>;tag=as05be8e95
To: <sip:19712448056@66.165.175.35>;tag=4042143d22c03347e15cee7ccb51d867-4e58
Call-ID: 3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12
CSeq: 103 REGISTER
PortaBilling: available-funds:100.00 currency:USD
Contact: <sip:19712448056@67.189.77.121>;expires=115
Server: Sip EXpress router (0.9.0 (i386/freebsd))
Content-Length: 0


Sep  4 13:08:52 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:52 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:52 DEBUG[30977] chan_sip.c: Stopping retransmission on '3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12' of Request 103: Match Found
Sep  4 13:08:52 DEBUG[30977] chan_sip.c: Registration successful
Sep  4 13:08:52 DEBUG[30977] chan_sip.c: Cancelling timeout 88
Sep  4 13:08:52 VERBOSE[30977] logger.c: Scheduling destruction of call '3c7df5654cbf970f0db6b3844a7cd1d7@10.1.1.12' in 32000 ms
Sep  4 13:08:52 NOTICE[30977] chan_sip.c: Outbound Registration: Expiry for 66.165.175.35 is 115 sec (Scheduling reregistration in 100 s)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57864, ts 14240, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57865, ts 14400, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57866, ts 14560, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57867, ts 14720, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57868, ts 14880, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57869, ts 15040, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57870, ts 15200, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57871, ts 15360, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57872, ts 15520, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57873, ts 15680, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57874, ts 15840, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57875, ts 16000, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57876, ts 16160, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57877, ts 16320, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57878, ts 16480, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57879, ts 16640, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57880, ts 16800, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57881, ts 16960, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57882, ts 17120, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57883, ts 17280, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57884, ts 17440, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57885, ts 17600, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57886, ts 17760, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57887, ts 17920, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57888, ts 18080, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57889, ts 18240, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57890, ts 18400, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57891, ts 18560, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57892, ts 18720, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57893, ts 18880, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57894, ts 19040, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57895, ts 19200, len 160)
Sep  4 13:08:52 VERBOSE[30977] logger.c: Sent RTP packet to 192.168.1.102:2266 (type 0, seq 57896, ts 19360, len 160)
Sep  4 13:08:52 DEBUG[30977] channel.c: Scheduling timer at 0 sample intervals
Sep  4 13:08:52 DEBUG[30977] channel.c: Scheduling timer at 0 sample intervals
Sep  4 13:08:52 DEBUG[30977] channel.c: Set channel SIP/354-2214 to write format ulaw
Sep  4 13:08:55 VERBOSE[30977] 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=z9hG4bKd40c37204EE97383
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>;tag=as485c04c8
CSeq: 2 BYE
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
Contact: <sip:354@192.168.1.102>
User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.3.1
Max-Forwards: 70
Content-Length: 0


Sep  4 13:08:55 VERBOSE[30977] logger.c: --- (10 headers 0 lines)Sep  4 13:08:55 VERBOSE[30977] logger.c: --- (10 headers 0 lines)---
Sep  4 13:08:55 VERBOSE[30977] logger.c: Sending to 192.168.1.102 : 5060 (NAT)
Sep  4 13:08:55 VERBOSE[30977] 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=z9hG4bKd40c37204EE97383;received=67.189.14.105;rport=5060
From: "354" <sip:354@67.189.77.121>;tag=BF0E45AD-89CE0F1A
To: <sip:*98@67.189.77.121>;tag=as485c04c8
Call-ID: 12086851-db20cc2f-892dac2c@192.168.1.102
CSeq: 2 BYE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:*98@67.189.77.121>
Content-Length: 0


---
Sep  4 13:08:55 WARNING[30977] app_voicemail.c: Couldn't read username
Sep  4 13:08:55 DEBUG[30977] pbx.c: Extension *98, priority 3 returned normally even though call was hung up
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1'
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1'
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] 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:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1'
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 'h' AND context = 'features' AND priority = '1'
Sep  4 13:08:55 DEBUG[30977] res_config_mysql.c: MySQL RealTime: Everything is fine.
Sep  4 13:08:55 DEBUG[30977] pbx.c: Spawn extension (from-internal,h,1) exited non-zero on 'SIP/354-2214'
Sep  4 13:08:55 DEBUG[30977] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep  4 13:08:55 DEBUG[30977] 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:08:49','\"External User\" <354>','354','*98','from-internal', 'SIP/354-2214','','Hangup','',6,6,'ANSWERED',3,'')
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is '"External User" <354>'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is '354'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is '*98'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is 'from-internal'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is 'SIP/354-2214'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is '(null)'
Sep  4 13:08:55 DEBUG[30977] pbx.c: Function result is 'Hangup'
Sep  4 13:08:55 NOTICE[30977] pbx.c: Error in extension logic (missing '}')
Sep  4 13:08:55 ERROR[30977] cdr_custom.c: Unable to re-open master file /var/log/asterisk/cdr-custom/Master.csv : No such file or directory
Sep  4 13:08:55 ERROR[30977] cdr_csv.c: Unable to re-open master file /var/log/asterisk//cdr-csv//Master.csv : No such file or directory
Sep  4 13:08:55 DEBUG[30977] channel.c: Hanging up channel 'SIP/354-2214'
Sep  4 13:08:55 DEBUG[30977] chan_sip.c: Hangup call SIP/354-2214, SIP callid 12086851-db20cc2f-892dac2c@192.168.1.102)
Sep  4 13:08:55 DEBUG[30977] chan_sip.c: update_call_counter(354) - decrement call limit counter
Sep  4 13:08:55 DEBUG[30977] app_queue.c: Device 'SIP/354' changed to state '1' (Not in use)
Sep  4 13:08:56 VERBOSE[30977] logger.c: Destroying call '12086851-db20cc2f-892dac2c@192.168.1.102'
Sep  4 13:08:57 VERBOSE[30977] logger.c: Beginning asterisk shutdown....
Sep  4 13:08:57 VERBOSE[30977] logger.c: Executing last minute cleanups
Sep  4 13:08:57 DEBUG[30977] res_musiconhold.c: killing 30987!
Sep  4 13:08:57 DEBUG[30977] res_musiconhold.c: mpg123 pid 30987 and child died after 2588160 bytes read
Sep  4 13:08:57 DEBUG[30977] res_musiconhold.c: killing 30985!
Sep  4 13:08:57 DEBUG[30977] res_musiconhold.c: mpg123 pid 30985 and child died after 6749184 bytes read
Sep  4 13:08:57 VERBOSE[30977] logger.c: Asterisk cleanly ending (0).